builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1472626596.59 results: success (0) buildid: 20160830211421 builduid: 2aea5207938d4aacaddc7e53c661031b revision: 7177e736768960cd807440961beee561ca7ce582 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.589121) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.589650) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.589966) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.620284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.620622) ========= 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=826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 23:56:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-08-30 23:56:37 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114709 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.752232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.752587) ========= 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=826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016204 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.802761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:36.803171) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7177e736768960cd807440961beee561ca7ce582 --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 7177e736768960cd807440961beee561ca7ce582 --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=826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 23:56:37,298 truncating revision to first 12 chars 2016-08-30 23:56:37,298 Setting DEBUG logging. 2016-08-30 23:56:37,298 attempt 1/10 2016-08-30 23:56:37,298 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7177e7367689?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 23:56:37,580 unpacking tar archive at: mozilla-aurora-7177e7367689/testing/mozharness/ program finished with exit code 0 elapsedTime=0.579630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:37.398904) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:37.399401) ========= script_repo_revision: 7177e736768960cd807440961beee561ca7ce582 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:37.400261) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:37.400762) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 23:56:37.414476) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-30 23:56:37.414868) ========= /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=826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:56:37 INFO - MultiFileLogger online at 20160830 23:56:37 in /builds/slave/test 23:56:37 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 23:56:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:56:37 INFO - {'append_to_log': False, 23:56:37 INFO - 'base_work_dir': '/builds/slave/test', 23:56:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:56:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:56:37 INFO - 'branch': 'Mozilla-Aurora', 23:56:37 INFO - 'buildbot_json_path': 'buildprops.json', 23:56:37 INFO - 'config_files': ('talos/linux_config.py',), 23:56:37 INFO - 'default_actions': ('clobber', 23:56:37 INFO - 'read-buildbot-config', 23:56:37 INFO - 'download-and-extract', 23:56:37 INFO - 'populate-webroot', 23:56:37 INFO - 'create-virtualenv', 23:56:37 INFO - 'install', 23:56:37 INFO - 'run-tests'), 23:56:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:56:37 INFO - 'download_minidump_stackwalk': True, 23:56:37 INFO - 'download_symbols': 'ondemand', 23:56:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:56:37 INFO - 'tooltool.py': '/tools/tooltool.py', 23:56:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:56:37 INFO - '/tools/misc-python/virtualenv.py')}, 23:56:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:56:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:56:37 INFO - 'installer_path': 'installer.exe', 23:56:37 INFO - 'log_level': 'info', 23:56:37 INFO - 'log_name': 'talos', 23:56:37 INFO - 'log_to_console': True, 23:56:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:56:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:56:37 INFO - 'opt_config_files': (), 23:56:37 INFO - 'pip_index': False, 23:56:37 INFO - 'sps_profile': False, 23:56:37 INFO - 'sps_profile_interval': 0, 23:56:37 INFO - 'suite': 'dromaeojs', 23:56:37 INFO - 'system_bits': '32', 23:56:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:56:37 INFO - 'title': 'talos-linux64-ix-052', 23:56:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:56:37 INFO - 'use_talos_json': True, 23:56:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:56:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:56:37 INFO - 'work_dir': 'build'} 23:56:37 INFO - [mozharness: 2016-08-31 06:56:37.998853Z] Running clobber step. 23:56:37 INFO - Running pre-action listener: _resource_record_pre_action 23:56:37 INFO - Running main action method: clobber 23:56:37 INFO - rmtree: /builds/slave/test/build 23:56:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:56:38 INFO - Running post-action listener: _resource_record_post_action 23:56:38 INFO - [mozharness: 2016-08-31 06:56:38.977452Z] Finished clobber step (success) 23:56:38 INFO - [mozharness: 2016-08-31 06:56:38.977554Z] Running read-buildbot-config step. 23:56:38 INFO - Running pre-action listener: _resource_record_pre_action 23:56:38 INFO - Running main action method: read_buildbot_config 23:56:38 INFO - Using buildbot properties: 23:56:38 INFO - { 23:56:38 INFO - "project": "", 23:56:38 INFO - "product": "firefox", 23:56:38 INFO - "script_repo_revision": "production", 23:56:38 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 23:56:38 INFO - "repository": "", 23:56:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 23:56:38 INFO - "buildid": "20160830211421", 23:56:38 INFO - "pgo_build": "True", 23:56:38 INFO - "basedir": "/builds/slave/test", 23:56:38 INFO - "buildnumber": 502, 23:56:38 INFO - "slavename": "talos-linux64-ix-052", 23:56:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:56:38 INFO - "platform": "ubuntu64_hw", 23:56:38 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 23:56:38 INFO - "branch": "mozilla-aurora", 23:56:38 INFO - "repo_path": "releases/mozilla-aurora", 23:56:38 INFO - "revision": "7177e736768960cd807440961beee561ca7ce582", 23:56:38 INFO - "stage_platform": "linux64-pgo", 23:56:38 INFO - "builduid": "2aea5207938d4aacaddc7e53c661031b", 23:56:38 INFO - "slavebuilddir": "test" 23:56:38 INFO - } 23:56:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 23:56:38 INFO - Running post-action listener: _resource_record_post_action 23:56:38 INFO - [mozharness: 2016-08-31 06:56:38.979980Z] Finished read-buildbot-config step (success) 23:56:38 INFO - [mozharness: 2016-08-31 06:56:38.980070Z] Running download-and-extract step. 23:56:38 INFO - Running pre-action listener: _resource_record_pre_action 23:56:38 INFO - Running main action method: download_and_extract 23:56:38 INFO - mkdir: /builds/slave/test/build/tests 23:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:56:38 INFO - https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:56:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:56:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:56:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 23:56:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 23:56:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:56:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:56:38 INFO - retry: Failed, sleeping 30 seconds before retrying 23:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 23:57:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:57:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:57:09 INFO - retry: Failed, sleeping 60 seconds before retrying 23:58:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 23:58:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:58:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:58:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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! 23:58:09 INFO - Caught exception: HTTP Error 404: Not Found 23:58:09 INFO - Caught exception: HTTP Error 404: Not Found 23:58:09 INFO - Caught exception: HTTP Error 404: Not Found 23:58:09 INFO - trying https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:58:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 23:58:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 23:58:09 INFO - Downloaded 1361 bytes. 23:58:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:58:09 INFO - Using the following test package requirements: 23:58:09 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 23:58:09 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 23:58:09 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 23:58:09 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'jsshell-linux-x86_64.zip'], 23:58:09 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 23:58:09 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 23:58:09 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 23:58:09 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 23:58:09 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 23:58:09 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:58:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 23:58:09 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:58:09 INFO - https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:58:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:58:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:58:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:58:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:58:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:58:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:58:09 INFO - retry: Failed, sleeping 30 seconds before retrying 23:58:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 23:58:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:58:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:58:39 INFO - retry: Failed, sleeping 60 seconds before retrying 23:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 23:59:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:59:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:59:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 23:59:39 INFO - Caught exception: HTTP Error 404: Not Found 23:59:39 INFO - Caught exception: HTTP Error 404: Not Found 23:59:39 INFO - Caught exception: HTTP Error 404: Not Found 23:59:39 INFO - trying https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:59:39 INFO - Downloading https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:59:40 INFO - Downloaded 21771331 bytes. 23:59:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:59:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:59:41 INFO - Return code: 0 23:59:41 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 23:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:59:41 INFO - https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:59:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:59:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:59:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:59:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:59:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:59:41 INFO - retry: Failed, sleeping 30 seconds before retrying 00:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:00:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:00:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:00:11 INFO - retry: Failed, sleeping 60 seconds before retrying 00:01:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:01:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:01:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:01:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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! 00:01:11 INFO - Caught exception: HTTP Error 404: Not Found 00:01:11 INFO - Caught exception: HTTP Error 404: Not Found 00:01:11 INFO - Caught exception: HTTP Error 404: Not Found 00:01:11 INFO - trying https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:01:11 INFO - Downloading https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:01:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:01:12 INFO - Downloaded 21771331 bytes. 00:01:12 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'] 00:01:12 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 00:01:14 INFO - Return code: 0 00:01:14 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')]} 00:01:14 INFO - https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 00:01:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:01:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:01:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:01:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:01:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:01:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 00:01:14 INFO - retry: Failed, sleeping 30 seconds before retrying 00:01:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:01:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:01:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:01:44 INFO - retry: Failed, sleeping 60 seconds before retrying 00:02:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:02:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:02:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:02:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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! 00:02:44 INFO - Caught exception: HTTP Error 404: Not Found 00:02:44 INFO - Caught exception: HTTP Error 404: Not Found 00:02:44 INFO - Caught exception: HTTP Error 404: Not Found 00:02:44 INFO - trying https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:02:44 INFO - Downloading https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:02:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:02:45 INFO - Downloaded 14413740 bytes. 00:02:45 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'] 00:02:45 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 00:02:45 INFO - Return code: 0 00:02:45 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')]} 00:02:45 INFO - https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 00:02:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:02:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:02:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:02:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:02:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:02:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 00:02:45 INFO - retry: Failed, sleeping 30 seconds before retrying 00:03:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:03:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:03:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:03:15 INFO - retry: Failed, sleeping 60 seconds before retrying 00:04:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:04:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:04:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:04:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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! 00:04:15 INFO - Caught exception: HTTP Error 404: Not Found 00:04:15 INFO - Caught exception: HTTP Error 404: Not Found 00:04:15 INFO - Caught exception: HTTP Error 404: Not Found 00:04:15 INFO - trying https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:04:15 INFO - Downloading https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:04:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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 00:04:17 INFO - Downloaded 63889834 bytes. 00:04:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:04:17 INFO - mkdir: /builds/slave/test/properties 00:04:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:04:17 INFO - Writing to file /builds/slave/test/properties/build_url 00:04:17 INFO - Contents: 00:04:17 INFO - build_url:https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:04:18 INFO - Running post-action listener: _resource_record_post_action 00:04:18 INFO - Running post-action listener: set_extra_try_arguments 00:04:18 INFO - [mozharness: 2016-08-31 07:04:18.632438Z] Finished download-and-extract step (success) 00:04:18 INFO - [mozharness: 2016-08-31 07:04:18.632673Z] Running populate-webroot step. 00:04:18 INFO - Running pre-action listener: _resource_record_pre_action 00:04:18 INFO - Running main action method: populate_webroot 00:04:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:04:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:04:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:04:18 INFO - '--tppagecycles', 00:04:18 INFO - '7'], 00:04:18 INFO - 'tests': ['tsvgm']}}, 00:04:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:04:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:04:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:04:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:04:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:04:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:04:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['damp', 'tps']}, 00:04:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'tests': ['damp', 'tps']}, 00:04:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['dromaeo_dom']}, 00:04:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:04:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['basic_compositor_video']}, 00:04:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:04:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['a11yr', 00:04:18 INFO - 'ts_paint', 00:04:18 INFO - 'tpaint', 00:04:18 INFO - 'sessionrestore', 00:04:18 INFO - 'sessionrestore_no_auto_restore', 00:04:18 INFO - 'tabpaint']}, 00:04:18 INFO - 'other-e10s': {'tests': ['a11yr', 00:04:18 INFO - 'ts_paint', 00:04:18 INFO - 'tpaint', 00:04:18 INFO - 'sessionrestore', 00:04:18 INFO - 'sessionrestore_no_auto_restore', 00:04:18 INFO - 'tabpaint']}, 00:04:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:04:18 INFO - 'ts_paint', 00:04:18 INFO - 'tpaint', 00:04:18 INFO - 'sessionrestore', 00:04:18 INFO - 'sessionrestore_no_auto_restore', 00:04:18 INFO - 'tabpaint']}, 00:04:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:04:18 INFO - 'ts_paint', 00:04:18 INFO - 'tpaint', 00:04:18 INFO - 'sessionrestore', 00:04:18 INFO - 'sessionrestore_no_auto_restore', 00:04:18 INFO - 'tabpaint']}, 00:04:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['a11yr', 00:04:18 INFO - 'ts_paint', 00:04:18 INFO - 'tpaint', 00:04:18 INFO - 'sessionrestore', 00:04:18 INFO - 'sessionrestore_no_auto_restore', 00:04:18 INFO - 'tabpaint']}, 00:04:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['a11yr', 00:04:18 INFO - 'ts_paint', 00:04:18 INFO - 'tpaint', 00:04:18 INFO - 'sessionrestore', 00:04:18 INFO - 'sessionrestore_no_auto_restore', 00:04:18 INFO - 'tabpaint']}, 00:04:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['tsvgx', 00:04:18 INFO - 'tsvgr_opacity', 00:04:18 INFO - 'tart', 00:04:18 INFO - 'tscrollx', 00:04:18 INFO - 'cart']}, 00:04:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:04:18 INFO - 'tsvgr_opacity', 00:04:18 INFO - 'tart', 00:04:18 INFO - 'tscrollx', 00:04:18 INFO - 'cart']}, 00:04:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'talos_options': ['--disable-e10s'], 00:04:18 INFO - 'tests': ['tp5o']}, 00:04:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'tests': ['tp5o']}, 00:04:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'talos_options': ['--disable-e10s', 00:04:18 INFO - '--xperf_path', 00:04:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:04:18 INFO - 'tests': ['tp5n']}, 00:04:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:18 INFO - 'talos_options': ['--xperf_path', 00:04:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:04:18 INFO - 'tests': ['tp5n']}}, 00:04:18 INFO - 'talos.zip': {'path': '', 00:04:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:04:18 INFO - Running post-action listener: _resource_record_post_action 00:04:18 INFO - [mozharness: 2016-08-31 07:04:18.651791Z] Finished populate-webroot step (success) 00:04:18 INFO - [mozharness: 2016-08-31 07:04:18.651881Z] Running create-virtualenv step. 00:04:18 INFO - Running pre-action listener: _resource_record_pre_action 00:04:18 INFO - Running main action method: create_virtualenv 00:04:18 INFO - Creating virtualenv /builds/slave/test/build/venv 00:04:18 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 00:04:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:04:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:04:18 INFO - Using real prefix '/usr' 00:04:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:04:19 INFO - Installing distribute.............................................................................................................................................................................................done. 00:04:21 INFO - Installing pip.................done. 00:04:21 INFO - Return code: 0 00:04:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:04: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')]} 00:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04: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')]} 00:04:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:21 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:21 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 00:04:21 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 00:04:21 INFO - Using env: {'DISPLAY': ':0', 00:04:21 INFO - 'HOME': '/home/cltbld', 00:04:21 INFO - 'LANG': 'en_US.UTF-8', 00:04:21 INFO - 'LANGUAGE': 'en_US:en', 00:04:21 INFO - 'LOGNAME': 'cltbld', 00:04:21 INFO - 'MAIL': '/var/mail/cltbld', 00:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:21 INFO - 'MOZ_NO_REMOTE': '1', 00:04:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:21 INFO - 'NO_EM_RESTART': '1', 00:04:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:21 INFO - 'PWD': '/builds/slave/test', 00:04:21 INFO - 'SHELL': '/bin/bash', 00:04:21 INFO - 'SHLVL': '1', 00:04:21 INFO - 'TERM': 'linux', 00:04:21 INFO - 'TMOUT': '86400', 00:04:21 INFO - 'USER': 'cltbld', 00:04:21 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:21 INFO - '_': '/tools/buildbot/bin/python'} 00:04:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:21 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 00:04:21 INFO - Cleaning up... 00:04:21 INFO - Return code: 0 00:04:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:04: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')]} 00:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04: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')]} 00:04:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:21 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:21 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 00:04:21 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 00:04:21 INFO - Using env: {'DISPLAY': ':0', 00:04:21 INFO - 'HOME': '/home/cltbld', 00:04:21 INFO - 'LANG': 'en_US.UTF-8', 00:04:21 INFO - 'LANGUAGE': 'en_US:en', 00:04:21 INFO - 'LOGNAME': 'cltbld', 00:04:21 INFO - 'MAIL': '/var/mail/cltbld', 00:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:21 INFO - 'MOZ_NO_REMOTE': '1', 00:04:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:21 INFO - 'NO_EM_RESTART': '1', 00:04:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:21 INFO - 'PWD': '/builds/slave/test', 00:04:21 INFO - 'SHELL': '/bin/bash', 00:04:21 INFO - 'SHLVL': '1', 00:04:21 INFO - 'TERM': 'linux', 00:04:21 INFO - 'TMOUT': '86400', 00:04:21 INFO - 'USER': 'cltbld', 00:04:21 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:21 INFO - '_': '/tools/buildbot/bin/python'} 00:04:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:21 INFO - Downloading/unpacking psutil>=3.1.1 00:04:21 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 00:04:21 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 00:04:21 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 00:04:21 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 00:04:21 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 00:04:21 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 00:04:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:04:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:04:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:04:23 INFO - Installing collected packages: psutil 00:04:23 INFO - Running setup.py install for psutil 00:04:23 INFO - building 'psutil._psutil_linux' extension 00:04:23 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 00:04:23 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 00:04:23 INFO - building 'psutil._psutil_posix' extension 00:04:23 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 00:04:24 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 00:04:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:04:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:04:24 INFO - Successfully installed psutil 00:04:24 INFO - Cleaning up... 00:04:24 INFO - Return code: 0 00:04:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:04:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:04:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:04:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:04:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:04:24 INFO - Using env: {'DISPLAY': ':0', 00:04:24 INFO - 'HOME': '/home/cltbld', 00:04:24 INFO - 'LANG': 'en_US.UTF-8', 00:04:24 INFO - 'LANGUAGE': 'en_US:en', 00:04:24 INFO - 'LOGNAME': 'cltbld', 00:04:24 INFO - 'MAIL': '/var/mail/cltbld', 00:04:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:24 INFO - 'MOZ_NO_REMOTE': '1', 00:04:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:24 INFO - 'NO_EM_RESTART': '1', 00:04:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:24 INFO - 'PWD': '/builds/slave/test', 00:04:24 INFO - 'SHELL': '/bin/bash', 00:04:24 INFO - 'SHLVL': '1', 00:04:24 INFO - 'TERM': 'linux', 00:04:24 INFO - 'TMOUT': '86400', 00:04:24 INFO - 'USER': 'cltbld', 00:04:24 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:24 INFO - '_': '/tools/buildbot/bin/python'} 00:04:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:04:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:04:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:04:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:04:26 INFO - Installing collected packages: mozsystemmonitor 00:04:26 INFO - Running setup.py install for mozsystemmonitor 00:04:26 INFO - Successfully installed mozsystemmonitor 00:04:26 INFO - Cleaning up... 00:04:26 INFO - Return code: 0 00:04:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:04: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')]} 00:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04: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')]} 00:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 00:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 00:04:26 INFO - Using env: {'DISPLAY': ':0', 00:04:26 INFO - 'HOME': '/home/cltbld', 00:04:26 INFO - 'LANG': 'en_US.UTF-8', 00:04:26 INFO - 'LANGUAGE': 'en_US:en', 00:04:26 INFO - 'LOGNAME': 'cltbld', 00:04:26 INFO - 'MAIL': '/var/mail/cltbld', 00:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:26 INFO - 'MOZ_NO_REMOTE': '1', 00:04:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:26 INFO - 'NO_EM_RESTART': '1', 00:04:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:26 INFO - 'PWD': '/builds/slave/test', 00:04:26 INFO - 'SHELL': '/bin/bash', 00:04:26 INFO - 'SHLVL': '1', 00:04:26 INFO - 'TERM': 'linux', 00:04:26 INFO - 'TMOUT': '86400', 00:04:26 INFO - 'USER': 'cltbld', 00:04:26 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:26 INFO - '_': '/tools/buildbot/bin/python'} 00:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:26 INFO - Downloading/unpacking jsonschema==2.5.1 00:04:26 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 00:04:26 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 00:04:26 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 00:04:26 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 00:04:26 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 00:04:26 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 00:04:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:04:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:04:28 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 00:04:28 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 00:04:28 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 00:04:28 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 00:04:28 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 00:04:28 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 00:04:28 INFO - Downloading functools32-3.2.3-2.tar.gz 00:04:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:04:28 INFO - warning: no files found matching '*.txt' 00:04:28 INFO - no previously-included directories found matching 'build' 00:04:28 INFO - no previously-included directories found matching 'dist' 00:04:28 INFO - no previously-included directories found matching '.git*' 00:04:28 INFO - Installing collected packages: jsonschema, functools32 00:04:28 INFO - Running setup.py install for functools32 00:04:29 INFO - warning: no files found matching '*.txt' 00:04:29 INFO - no previously-included directories found matching 'build' 00:04:29 INFO - no previously-included directories found matching 'dist' 00:04:29 INFO - no previously-included directories found matching '.git*' 00:04:29 INFO - Successfully installed jsonschema functools32 00:04:29 INFO - Cleaning up... 00:04:29 INFO - Return code: 0 00:04:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:04:29 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')]} 00:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:29 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')]} 00:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:29 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:29 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 00:04:29 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 00:04:29 INFO - Using env: {'DISPLAY': ':0', 00:04:29 INFO - 'HOME': '/home/cltbld', 00:04:29 INFO - 'LANG': 'en_US.UTF-8', 00:04:29 INFO - 'LANGUAGE': 'en_US:en', 00:04:29 INFO - 'LOGNAME': 'cltbld', 00:04:29 INFO - 'MAIL': '/var/mail/cltbld', 00:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:29 INFO - 'MOZ_NO_REMOTE': '1', 00:04:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:29 INFO - 'NO_EM_RESTART': '1', 00:04:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:29 INFO - 'PWD': '/builds/slave/test', 00:04:29 INFO - 'SHELL': '/bin/bash', 00:04:29 INFO - 'SHLVL': '1', 00:04:29 INFO - 'TERM': 'linux', 00:04:29 INFO - 'TMOUT': '86400', 00:04:29 INFO - 'USER': 'cltbld', 00:04:29 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:29 INFO - '_': '/tools/buildbot/bin/python'} 00:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:04:29 INFO - Cleaning up... 00:04:29 INFO - Return code: 0 00:04:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:04:29 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')]} 00:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:29 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')]} 00:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:29 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:29 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 00:04:29 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 00:04:29 INFO - Using env: {'DISPLAY': ':0', 00:04:29 INFO - 'HOME': '/home/cltbld', 00:04:29 INFO - 'LANG': 'en_US.UTF-8', 00:04:29 INFO - 'LANGUAGE': 'en_US:en', 00:04:29 INFO - 'LOGNAME': 'cltbld', 00:04:29 INFO - 'MAIL': '/var/mail/cltbld', 00:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:29 INFO - 'MOZ_NO_REMOTE': '1', 00:04:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:29 INFO - 'NO_EM_RESTART': '1', 00:04:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:29 INFO - 'PWD': '/builds/slave/test', 00:04:29 INFO - 'SHELL': '/bin/bash', 00:04:29 INFO - 'SHLVL': '1', 00:04:29 INFO - 'TERM': 'linux', 00:04:29 INFO - 'TMOUT': '86400', 00:04:29 INFO - 'USER': 'cltbld', 00:04:29 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:29 INFO - '_': '/tools/buildbot/bin/python'} 00:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:29 INFO - Downloading/unpacking blobuploader==1.2.4 00:04:29 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 00:04:29 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 00:04:29 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 00:04:29 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 00:04:29 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 00:04:29 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 00:04:31 INFO - Downloading blobuploader-1.2.4.tar.gz 00:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:04:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:04:31 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 00:04:31 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 00:04:31 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 00:04:31 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 00:04:31 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 00:04:31 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 00:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:04:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:04:31 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 00:04:31 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 00:04:31 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 00:04:31 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 00:04:31 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 00:04:31 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 00:04:32 INFO - Downloading docopt-0.6.1.tar.gz 00:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:04:32 INFO - Installing collected packages: blobuploader, requests, docopt 00:04:32 INFO - Running setup.py install for blobuploader 00:04:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:04:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:04:32 INFO - Running setup.py install for requests 00:04:32 INFO - Running setup.py install for docopt 00:04:32 INFO - Successfully installed blobuploader requests docopt 00:04:32 INFO - Cleaning up... 00:04:32 INFO - Return code: 0 00:04:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:04: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')]} 00:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04: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')]} 00:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:32 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:32 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 00:04:32 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 00:04:32 INFO - Using env: {'DISPLAY': ':0', 00:04:32 INFO - 'HOME': '/home/cltbld', 00:04:32 INFO - 'LANG': 'en_US.UTF-8', 00:04:32 INFO - 'LANGUAGE': 'en_US:en', 00:04:32 INFO - 'LOGNAME': 'cltbld', 00:04:32 INFO - 'MAIL': '/var/mail/cltbld', 00:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:32 INFO - 'MOZ_NO_REMOTE': '1', 00:04:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:32 INFO - 'NO_EM_RESTART': '1', 00:04:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:32 INFO - 'PWD': '/builds/slave/test', 00:04:32 INFO - 'SHELL': '/bin/bash', 00:04:32 INFO - 'SHLVL': '1', 00:04:32 INFO - 'TERM': 'linux', 00:04:32 INFO - 'TMOUT': '86400', 00:04:32 INFO - 'USER': 'cltbld', 00:04:32 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:32 INFO - '_': '/tools/buildbot/bin/python'} 00:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:04:32 INFO - Running setup.py (path:/tmp/pip-au2KmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:04:33 INFO - Running setup.py (path:/tmp/pip-jNFRah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:04:33 INFO - Running setup.py (path:/tmp/pip-RI67ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:04:33 INFO - Running setup.py (path:/tmp/pip-4ET4nv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:04:33 INFO - Running setup.py (path:/tmp/pip-o5BRq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:04:33 INFO - Running setup.py (path:/tmp/pip-KLE4tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:04:33 INFO - Running setup.py (path:/tmp/pip-FOTEet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:04:33 INFO - Running setup.py (path:/tmp/pip-Ygdd4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:04:33 INFO - Running setup.py (path:/tmp/pip-V8CvCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:04:33 INFO - Running setup.py (path:/tmp/pip-IARSrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:04:33 INFO - Running setup.py (path:/tmp/pip-S6tn5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:04:33 INFO - Running setup.py (path:/tmp/pip-pJvbLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:04:33 INFO - Running setup.py (path:/tmp/pip-Mktu2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:04:33 INFO - Running setup.py (path:/tmp/pip-6OGD5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:34 INFO - Running setup.py (path:/tmp/pip-iPtSsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:04:34 INFO - Running setup.py (path:/tmp/pip-p3e_Le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:04:34 INFO - Running setup.py (path:/tmp/pip-_Vulr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:04:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:04:34 INFO - Running setup.py install for manifestparser 00:04:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:04:34 INFO - Running setup.py install for mozcrash 00:04:34 INFO - Running setup.py install for mozdebug 00:04:34 INFO - Running setup.py install for mozdevice 00:04:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:04:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:04:34 INFO - Running setup.py install for mozfile 00:04:34 INFO - Running setup.py install for mozhttpd 00:04:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:04:34 INFO - Running setup.py install for mozinfo 00:04:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:04:34 INFO - Running setup.py install for mozInstall 00:04:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Running setup.py install for mozleak 00:04:35 INFO - Running setup.py install for mozlog 00:04:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Running setup.py install for moznetwork 00:04:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Running setup.py install for mozprocess 00:04:35 INFO - Running setup.py install for mozprofile 00:04:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Running setup.py install for mozrunner 00:04:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Running setup.py install for mozscreenshot 00:04:35 INFO - Running setup.py install for moztest 00:04:35 INFO - Running setup.py install for mozversion 00:04:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:04:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:04:35 INFO - Cleaning up... 00:04:36 INFO - Return code: 0 00:04:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:36 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:36 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 00:04:36 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 00:04:36 INFO - Using env: {'DISPLAY': ':0', 00:04:36 INFO - 'HOME': '/home/cltbld', 00:04:36 INFO - 'LANG': 'en_US.UTF-8', 00:04:36 INFO - 'LANGUAGE': 'en_US:en', 00:04:36 INFO - 'LOGNAME': 'cltbld', 00:04:36 INFO - 'MAIL': '/var/mail/cltbld', 00:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:36 INFO - 'MOZ_NO_REMOTE': '1', 00:04:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:36 INFO - 'NO_EM_RESTART': '1', 00:04:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:36 INFO - 'PWD': '/builds/slave/test', 00:04:36 INFO - 'SHELL': '/bin/bash', 00:04:36 INFO - 'SHLVL': '1', 00:04:36 INFO - 'TERM': 'linux', 00:04:36 INFO - 'TMOUT': '86400', 00:04:36 INFO - 'USER': 'cltbld', 00:04:36 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:36 INFO - '_': '/tools/buildbot/bin/python'} 00:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:04:36 INFO - Running setup.py (path:/tmp/pip-FJ0QCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:04:36 INFO - Running setup.py (path:/tmp/pip-RGLTtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:04:36 INFO - Running setup.py (path:/tmp/pip-qo46Rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:04:36 INFO - Running setup.py (path:/tmp/pip-wtZ8K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:04:36 INFO - Running setup.py (path:/tmp/pip-Rqh9Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:04:36 INFO - Running setup.py (path:/tmp/pip-Ln5NPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:04:36 INFO - Running setup.py (path:/tmp/pip-TZhqpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:04:36 INFO - Running setup.py (path:/tmp/pip-8dUdB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:04:36 INFO - Running setup.py (path:/tmp/pip-8wY8jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:04:36 INFO - Running setup.py (path:/tmp/pip-wwv0zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:04:36 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)) 00:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:04:36 INFO - Running setup.py (path:/tmp/pip-2iGQoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:04:37 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)) 00:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:04:37 INFO - Running setup.py (path:/tmp/pip-J70xJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:04:37 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)) 00:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:04:37 INFO - Running setup.py (path:/tmp/pip-5Ite8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:04:37 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)) 00:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:04:37 INFO - Running setup.py (path:/tmp/pip-am9rgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:04:37 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)) 00:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:37 INFO - Running setup.py (path:/tmp/pip-Tcu9cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:37 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)) 00:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:04:37 INFO - Running setup.py (path:/tmp/pip-1P2ALa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:04:37 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)) 00:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:04:37 INFO - Running setup.py (path:/tmp/pip-Sjj99l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:04:37 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)) 00:04:37 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)) 00:04:37 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)) 00:04:37 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)) 00:04:37 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)) 00:04:37 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)) 00:04:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:04: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 00:04: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 00:04: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 00:04: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 00:04: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 00:04: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 00:04:39 INFO - Downloading blessings-1.6.tar.gz 00:04:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:04:39 INFO - Installing collected packages: blessings 00:04:39 INFO - Running setup.py install for blessings 00:04:39 INFO - Successfully installed blessings 00:04:39 INFO - Cleaning up... 00:04:39 INFO - Return code: 0 00:04:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:04:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:04:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:04:39 INFO - Reading from file tmpfile_stdout 00:04:39 INFO - Current package versions: 00:04:39 INFO - argparse == 1.2.1 00:04:39 INFO - blessings == 1.6 00:04:39 INFO - blobuploader == 1.2.4 00:04:39 INFO - docopt == 0.6.1 00:04:39 INFO - functools32 == 3.2.3-2 00:04:39 INFO - jsonschema == 2.5.1 00:04:39 INFO - manifestparser == 1.1 00:04:39 INFO - mozInstall == 1.12 00:04:39 INFO - mozcrash == 0.17 00:04:39 INFO - mozdebug == 0.1 00:04:39 INFO - mozdevice == 0.48 00:04:39 INFO - mozfile == 1.2 00:04:39 INFO - mozhttpd == 0.7 00:04:39 INFO - mozinfo == 0.9 00:04:39 INFO - mozleak == 0.1 00:04:39 INFO - mozlog == 3.3 00:04:39 INFO - moznetwork == 0.27 00:04:39 INFO - mozprocess == 0.23 00:04:39 INFO - mozprofile == 0.28 00:04:39 INFO - mozrunner == 6.12 00:04:39 INFO - mozscreenshot == 0.1 00:04:39 INFO - mozsystemmonitor == 0.3 00:04:39 INFO - moztest == 0.7 00:04:39 INFO - mozversion == 1.4 00:04:39 INFO - psutil == 3.1.1 00:04:39 INFO - requests == 1.2.3 00:04:39 INFO - wsgiref == 0.1.2 00:04:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:04: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')]} 00:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04: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')]} 00:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:39 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:39 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 00:04:39 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 00:04:39 INFO - Using env: {'DISPLAY': ':0', 00:04:39 INFO - 'HOME': '/home/cltbld', 00:04:39 INFO - 'LANG': 'en_US.UTF-8', 00:04:39 INFO - 'LANGUAGE': 'en_US:en', 00:04:39 INFO - 'LOGNAME': 'cltbld', 00:04:39 INFO - 'MAIL': '/var/mail/cltbld', 00:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:39 INFO - 'MOZ_NO_REMOTE': '1', 00:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:39 INFO - 'NO_EM_RESTART': '1', 00:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:39 INFO - 'PWD': '/builds/slave/test', 00:04:39 INFO - 'SHELL': '/bin/bash', 00:04:39 INFO - 'SHLVL': '1', 00:04:39 INFO - 'TERM': 'linux', 00:04:39 INFO - 'TMOUT': '86400', 00:04:39 INFO - 'USER': 'cltbld', 00:04:39 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:39 INFO - '_': '/tools/buildbot/bin/python'} 00:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 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)) 00:04:40 INFO - Cleaning up... 00:04:40 INFO - Return code: 0 00:04:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:04:40 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')]} 00:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:40 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')]} 00:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:40 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 0x1c4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, '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': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', '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 00:04:40 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 00:04:40 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 00:04:40 INFO - Using env: {'DISPLAY': ':0', 00:04:40 INFO - 'HOME': '/home/cltbld', 00:04:40 INFO - 'LANG': 'en_US.UTF-8', 00:04:40 INFO - 'LANGUAGE': 'en_US:en', 00:04:40 INFO - 'LOGNAME': 'cltbld', 00:04:40 INFO - 'MAIL': '/var/mail/cltbld', 00:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:40 INFO - 'MOZ_NO_REMOTE': '1', 00:04:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:40 INFO - 'NO_EM_RESTART': '1', 00:04:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:40 INFO - 'PWD': '/builds/slave/test', 00:04:40 INFO - 'SHELL': '/bin/bash', 00:04:40 INFO - 'SHLVL': '1', 00:04:40 INFO - 'TERM': 'linux', 00:04:40 INFO - 'TMOUT': '86400', 00:04:40 INFO - 'USER': 'cltbld', 00:04:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:40 INFO - '_': '/tools/buildbot/bin/python'} 00:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:04:40 INFO - Cleaning up... 00:04:40 INFO - Return code: 0 00:04:40 INFO - Running post-action listener: _resource_record_post_action 00:04:40 INFO - Running post-action listener: _start_resource_monitoring 00:04:40 INFO - Starting resource monitoring. 00:04:40 INFO - [mozharness: 2016-08-31 07:04:40.294667Z] Finished create-virtualenv step (success) 00:04:40 INFO - [mozharness: 2016-08-31 07:04:40.295149Z] Running install step. 00:04:40 INFO - Running pre-action listener: _resource_record_pre_action 00:04:40 INFO - Running main action method: install 00:04:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:04:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:04:40 INFO - Reading from file tmpfile_stdout 00:04:40 INFO - Detecting whether we're running mozinstall >=1.0... 00:04:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:04:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:04:40 INFO - Reading from file tmpfile_stdout 00:04:40 INFO - Output received: 00:04:40 INFO - Usage: mozinstall [options] installer 00:04:40 INFO - Options: 00:04:40 INFO - -h, --help show this help message and exit 00:04:40 INFO - -d DEST, --destination=DEST 00:04:40 INFO - Directory to install application into. [default: 00:04:40 INFO - "/builds/slave/test"] 00:04:40 INFO - --app=APP Application being installed. [default: firefox] 00:04:40 INFO - mkdir: /builds/slave/test/build/application 00:04:40 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'] 00:04:40 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 00:04:57 INFO - Reading from file tmpfile_stdout 00:04:57 INFO - Output received: 00:04:57 INFO - /builds/slave/test/build/application/firefox/firefox 00:04:57 INFO - Running post-action listener: _resource_record_post_action 00:04:57 INFO - [mozharness: 2016-08-31 07:04:57.243648Z] Finished install step (success) 00:04:57 INFO - [mozharness: 2016-08-31 07:04:57.243935Z] Running run-tests step. 00:04:57 INFO - Running pre-action listener: _resource_record_pre_action 00:04:57 INFO - Running main action method: run_tests 00:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:04:57 INFO - Python 2.7.3 00:04:57 INFO - Return code: 0 00:04:57 INFO - grabbing minidump binary from tooltool 00:04: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')]} 00:04:57 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 0x1f0b3b0>, '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 0x1f0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:04:57 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 00:04:57 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 00:04:57 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 00:04:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:04:57 INFO - Return code: 0 00:04:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:04:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:04:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:04:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:04:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:04:57 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/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 00:04:57 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/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 00:04:57 INFO - Using env: {'DISPLAY': ':0', 00:04:57 INFO - 'HOME': '/home/cltbld', 00:04:57 INFO - 'LANG': 'en_US.UTF-8', 00:04:57 INFO - 'LANGUAGE': 'en_US:en', 00:04:57 INFO - 'LOGNAME': 'cltbld', 00:04:57 INFO - 'MAIL': '/var/mail/cltbld', 00:04:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:04:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:57 INFO - 'MOZ_NO_REMOTE': '1', 00:04:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:57 INFO - 'NO_EM_RESTART': '1', 00:04:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:57 INFO - 'PWD': '/builds/slave/test', 00:04:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:04:57 INFO - 'SHELL': '/bin/bash', 00:04:57 INFO - 'SHLVL': '1', 00:04:57 INFO - 'TERM': 'linux', 00:04:57 INFO - 'TMOUT': '86400', 00:04:57 INFO - 'USER': 'cltbld', 00:04:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694', 00:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:57 INFO - '_': '/tools/buildbot/bin/python'} 00:04:57 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/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 00:04:57 INFO - mozversion application_buildid: 20160830211421 00:04:57 INFO - mozversion application_changeset: 7177e736768960cd807440961beee561ca7ce582 00:04:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 00:04:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:04:57 INFO - mozversion application_name: Firefox 00:04:57 INFO - mozversion application_remotingname: firefox-dev 00:04:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:04:57 INFO - mozversion application_vendor: Mozilla 00:04:57 INFO - mozversion application_version: 50.0a2 00:04:57 INFO - mozversion platform_buildid: 20160830211421 00:04:57 INFO - mozversion platform_changeset: 7177e736768960cd807440961beee561ca7ce582 00:04:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:04:57 INFO - mozversion platform_version: 50.0a2 00:04:57 INFO - using testdate: 1472627097 00:04:57 INFO - actual date: 1472627097 00:04:57 INFO - starting webserver on 'localhost:39564' 00:04:57 INFO - SUITE-START | Running 2 tests 00:04:57 INFO - TEST-START | dromaeo_css 00:04:57 INFO - operating with platform_type : linux_ 00:04:57 INFO - Initialising browser for dromaeo_css test... 00:04:57 INFO - TEST-INFO | started process 10768 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdZtVOK/profile http://localhost:39564/getInfo.html) 00:05:01 INFO - PROCESS | 10768 | __metrics Screen width/height:1600/1200 00:05:01 INFO - PROCESS | 10768 | colorDepth:24 00:05:01 INFO - PROCESS | 10768 | Browser inner width/height: 1024/697 00:05:01 INFO - PROCESS | 10768 | __metrics 00:05:01 INFO - PROCESS | 10768 | 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" 00:05:01 INFO - PROCESS | 10768 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:05:02 INFO - TEST-INFO | 10768: exit 0 00:05:02 INFO - Browser initialized. 00:05:02 INFO - Running cycle 1/1 for dromaeo_css test... 00:05:02 INFO - TEST-INFO | started process 10854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdZtVOK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:05:14 INFO - PROCESS | 10854 | RSS: Main: 145383424 00:05:14 INFO - PROCESS | 10854 | 00:06:05 INFO - PROCESS | 10854 | 1472627165198 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] 00:08:14 INFO - PROCESS | 10854 | Cycle 1(1): loaded http://localhost:39564/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39564/tests/dromaeo/cssquery-ext.html) 00:08:15 INFO - PROCESS | 10854 | RSS: Main: 232013824 00:08:15 INFO - PROCESS | 10854 | 00:11:17 INFO - PROCESS | 10854 | Cycle 1(1): loaded http://localhost:39564/tests/dromaeo/cssquery-ext.html (next: http://localhost:39564/tests/dromaeo/cssquery-jquery.html) 00:11:18 INFO - PROCESS | 10854 | RSS: Main: 230334464 00:11:18 INFO - PROCESS | 10854 | 00:14:20 INFO - PROCESS | 10854 | Cycle 1(1): loaded http://localhost:39564/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39564/tests/dromaeo/cssquery-mootools.html) 00:14:20 INFO - PROCESS | 10854 | RSS: Main: 220209152 00:14:20 INFO - PROCESS | 10854 | 00:17:22 INFO - PROCESS | 10854 | Cycle 1(1): loaded http://localhost:39564/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39564/tests/dromaeo/cssquery-prototype.html) 00:17:23 INFO - PROCESS | 10854 | RSS: Main: 180121600 00:17:23 INFO - PROCESS | 10854 | 00:20:26 INFO - PROCESS | 10854 | Cycle 1(1): loaded http://localhost:39564/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39564/tests/dromaeo/cssquery-yui.html) 00:20:26 INFO - PROCESS | 10854 | RSS: Main: 181395456 00:20:26 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | Cycle 1(1): loaded http://localhost:39564/tests/dromaeo/cssquery-yui.html (next: http://localhost:39564/tests/dromaeo/cssquery-dojo.html) 00:23:29 INFO - PROCESS | 10854 | RSS: Main: 272072704 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | __start_tp_report 00:23:29 INFO - PROCESS | 10854 | _x_x_mozilla_page_load 00:23:29 INFO - PROCESS | 10854 | _x_x_mozilla_page_load_details 00:23:29 INFO - PROCESS | 10854 | |i|pagename|runs| 00:23:29 INFO - PROCESS | 10854 | |0;dojo.html;3197.6642452542483;3199.9520007199894;3201.615806103268;3214.148250713563;3261.445554255776;5457.099578569536;5580.023495888224;5598.748056337465;5621.4940655341115;5637.91543126853;5513.917291240625;5557.527610153132;5586.078297080977;5601.859953501154;5638.605297629168;4891.241857512091;4952.158133117378;5069.923951140736;5088.720120393377;5146.305248791403;4960.305557221992;4982.352294201749;5039.118154322997;5048.29323894655;5063.265826455162;5036.471170527078;5077;5099.974500127512;5126.231065340192;5142.04872098663;5179;5203.817866374697;5205.531502164805;5212.087884620214;5228.790848366079;4928.383952005999;4959.380077490314;4991;5085.694858308476;5089.618278629117;5420.647657902224;5438.401775804658;5469.480399362054;5484.314460692413;5493.423190564973;6020.548458865603;6027.939720602825;6033.6983150842725;6037.003894357425;6039.245094363205;5836.182934389189;5875.559333050039;5876.29484461863;5943.762249510014;6046.939530604682;5540.473655002768;5592.496675299202;5595;5597.44025597441;5603.467670571371;5774.94225057744;6052.513576802334;6081.726322315506;6082.513398928075;6088.4824789893355;5815.9709201453725;5852.473277404969;5860.150278209636;5938.376470470625;5972.611780234271;6067.998780201306;6071.51427885768;6074.51403887688;6076.4531192192035;6079.240094988127;5584.246126772915;6003.159557661931;6009.399060094043;6013.488853447506;6015.639061656227;5816.069428891442;5852.941470585325;5853.7365818538265;5926.762929482772;5952.226210592682;4554.658400619966;4562.543745625411;4563.452385713736;4566.725996440225;4589.885252868638;3984.183242435352;4077.510698716114;4082.0611259410502;4094.7952602369764;4118.91762164761;5571.247881536022;5602.607817452739;5622.212890195376;5624.91562626561;5626.409227031185;5960.4635582797755;5979.701014949235;5999.580029397987;6000.909986350208;6029.3970602940235;5481.780728770805;5525.944740552623;5672.943270567324;5678.659280443187;5680.8863822723315;5109.3613298337705;5170.095233334227;5178.948210517847;5194.844154675366;5202.817901373433;6122.724477398528;6138;6140.109684095781;6142.385761423911;6147.354527774609;5695.316562012419;5904.996150654485;5952.047672372572;5969.522438204846;5972.014617588049;6097.420745029215;6177.722002509988;6184.350643182581;6188.257409110935;6191.442770150708;5899.174115623902;5905.379935106924;5906.822795316148;5914.67469289176;5919.467247947706;5814.127880817911;5904.380040095728;5958.553108516792;5985.820425387245;6017.15759793638;5836.241288632451;5872.031114865999;5896;5915.940840591539;5940.198073260054;5808.912866306924;5818.44724751148;5856;5859.20900678403;5870.882582348244;5633.126865335879;5713.542916566747;5793.768249270151;5806.042003069446;5808.39011903744;5837.09525023622;5929.733162007807;5934.525237981037;5936.287645482397;5944.702764861826;5722.599418040863;5763.4812866842185;5764.7405866736935;5768.048272035067;5781.855453613525 00:23:29 INFO - PROCESS | 10854 | |1;ext.html;642.280645676842;658.2890478283455;664.3721683009558;671.7783131566581;673.4141297071549;46034.76982615086;48038.999999999956;48156.27765583515;52234;53007;49128.26307605389;49450.50549494504;49506.50493495064;49580.75209623956;49656;8461.15388461155;9120.270378369714;9215.95392023039;9251.861222081674;9259;10969.725756856064;11219.943900280487;11234.269772464804;11608.709782255426;11620.709482262926;11355.716107097305;11429.42852857358;11439.59961401351;11440.427978601077;11441.141914356456;11027.558897644085;11171.832422513588;11183.273087249343;11230.270032447947;11233.66299011027;11034.889651103547;11073;11104;11162.665120046411;11188.608398706006;45780;45920;46059.769701151614;46067.76966115148;46101.76949115266;3888.766673999569;3953.2686453006286;4115.012397024722;4158.521769996459;4621.953780462186;32484.187895302806;35105.473417898495;35170.82414587911;35589.82205088958;37116.257674846354;4447.666425018137;4480.596746292849;4492.887677808081;4510.8421205257655;4544.250198717207;394617;397328;398976;400082;400856;64998;65550.34449655442;65563.34436655667;65656.67171664111;65786;39656.20687586232;41175.1764964699;41280.38079428811;66702;67079;19741;19957.700634490495;19988;19988.50028749293;19991.800081999285;14033.649158770908;14288.571342859523;14314.355853986595;15374.231288435534;15768.921155394379;20932.686009709865;20945.37163885086;21057.157713691588;21179.364619061453;21230;7573.471365902922;7750.418718596128;7766.611669416619;7902.328302094272;7904.6838126474295;1037.273908264218;1067.4716015572187;1138.4137169357787;1179.899708524785;1257.4592925042136;34911.4763278551;34949.47575786365;35036;35145;35619.4657080144;1139.5897476908306;1169.9415029248673;1170.3504554972071;1178.6640807369859;1212.4665147335145;18368;18656.34702785396;18666.973316467724;18710.064496775107;18711.157997889855;625064;628154;630577;632431;633123;42381.728548143605;42670.786646067194;42671;42713.786431067645;42773.358399624034;1328.6744747536916;1448.608875603581;1450.6663467402557;1480;1486.041503230432;33809.32381352408;36132;36176.819115904254;36519.81740091283;36571.81714091413;53686.46313536815;61919;63373;63499.365006351196;69923.65038174776;524843;525440;525636;527965;528740;31415.84292078525;31484;31742.52386214255;33493.497597536545;50593;51693.22460163176;52229.216561752364;52376.73811630918;52639.473605263454;52999;44187.77906110449;44491.33263000993;44884.77557612191;45223.0955380884;45245.54754452413;45174.54825451703;45446.31830522611;45682;46478.76760616175;46537.767311163225;45283.09433811371;45828;46162;46177;46270.074598508516;38642.6135738639;40437.59562404338;40502.392464113655;40638.187236255704;41481;30569.23576910595;30584.388312234074;30610.540841886945;30963.845180774853;31190.22024449407 00:23:29 INFO - PROCESS | 10854 | |2;jquery.html;401.923634509443;410.71250124912564;414.56263641857845;417.6783876415158;450.8219253394909;15066.623334416645;19846.60306793863;20280.594388112248;20428.28501002465;20641.38075857723;19532.31636892709;19587;19593.706094408597;19626.313079042236;19819;1920.7695076590828;1921.077882616345;2074.8340132789376;4382.167388196249;6359.236891573018;6541.607503549779;6629.834254143637;6669.299723529032;6721.462283017347;6768.695408706595;6541.47668186544;6601.339866013408;6633.10453088834;6639.966800166017;6656.0348749431305;6432.903506447407;6653.0685704001;6683.966580167093;6722.798316050526;6730.226024007228;5958.344582095966;5964.105384192406;5966.761329546813;5973.522118230531;5975.52195824333;24101;24256.757432425627;24260.757392426025;24377.878110609334;24495.755042449524;2541.4789968056502;2812.31098380898;2851.9732896157366;2876.7267109624554;3272.4927636216426;10927.945360273148;11260.662180134608;11401.657950261506;14993.475228366955;15322.310496027594;2025;2103.6739305407687;2105.305249267738;2136.412486566191;2137.3801597536694;57596.42403575994;73354;74527;83899;87199.56400218085;11262.943685281522;11339.603113890975;11351.772964540829;11511.884881151249;11946.462409191607;16377.91811040961;16391.262393192334;16399.262033208535;16406.343746250015;16434.83565164357;8587.871181932276;8666.17671321236;8676.392652514263;8829.867551986725;8848.1151884811;8731.388802783744;8854.601542930584;8977.596008179647;9086;9161.770955726028;4865.732384718804;4995.425526064477;5002;5047.495250474996;5090.160123579568;2078.262216912974;2083.8020388063414;2096.8637038592747;2116.925907593227;2127.6808478728312;952.3476418653245;1035.7410647338165;1057.0169742139883;1061.2571200159919;1094.3379255550437;20439.693404598944;20720.689189662167;20762.792372076387;20947.581048378946;21320.573588528143;1007.3955626624113;1032.1691038713907;1040.38617215843;1043.88517263101;1105.7788442311569;9188.448693078437;10977.12183025356;11158.442077896236;11253.549858005752;11255.718607034889;523454;563387;563944;571100;574916;72415;82804.17195828086;84525.57737211275;93990;94966;1057.672121642278;1058.804121237558;1097.8023955687893;1106.2753896198003;1108.2740804772886;18119.275228990693;18441.72337414913;18448.723269151244;18457.984810835533;18878.71681924745;45219.32171017372;45607;45656;46689.53310466852;47527.52472475369;62005;62353;63457;63985;64563.67718161379;8079.192080791874;8203.384746144182;8444.957775211085;8484.278836298983;8643.611037503202;13206.537771177962;13535.25556094385;13833.10084844482;13900.443982241002;13937.372818223406;11435.028022618171;11604.129690273094;11610.477528511406;12121.515139394678;12251.448684809027;11563.248388854698;11755.941220294186;12056.578019769266;12286.754264914473;12326.198797078161;11376.374299413612;11587.652370428901;12129.150959432754;12333.075019373404;12909.806352904901;8253.79365515867;8327.37544684139;8416.621252043551;8423.157684231774;8655.307575393828;9041.77395565116;11531.135164862837;11539.36533490666;11543.134264930331;11560.595379161688 00:23:29 INFO - PROCESS | 10854 | |3;mootools.html;1458.5988853065403;1568.3726509396242;1705.1474262868567;1782.8662850286216;1906.0660276464528;9156.2217211537;9840.704778856629;9845.163161131313;9852.655157069505;9853.261005424594;8213.301869341098;9102.544872756353;9350.158485736283;9355.766105847357;9560.139587437146;2524.8358856674304;2528.127795910408;2568.4863027394504;2569.717331093578;2577.6133579962966;2385.4751954569965;2471.6292556116546;2472.8763561821925;2472.950540989179;2473.2209354053507;2496.1263557754824;2498.0757119865675;2573.7040240372407;2576.7294434084433;2577.716451190377;2450.5588993981078;2461.0770960889668;2467.6545283660303;2522.3946252899173;2527.4565968316715;2467.7778999889915;2475.7771800537857;2482.3918140055803;2486.254123762882;2503.173952595639;9641.084097010773;9751.561179746857;9787;9850.408975461496;9851.261155413373;5659.547236221134;5792.565557583198;5803.680797556132;5821.476067153976;5824.126381042835;3418.8290585470877;3531.94702079469;3618.4210526315665;3623.2572322673786;3634.528520605987;1099.4722533184035;1108.700650824281;1112.3603927741501;1124.9775004499863;1174.829649700797;13380.197188168644;13391.86608133916;13402.396892139877;13422.463101475929;13431.66420839477;4855.053264613437;5057.266696329012;5193.844184674465;5217.50433708797;5230.790768369223;11234.213605047567;11242;11245.775084498264;11261;11266.493007814504;5776.826695199151;5780.335261444988;5781.132830075522;5789.507891829242;5790.536757059425;4601.994397311474;4707.105649926503;5261.421243663194;5281.656692314988;5319.7340132993195;4384.649228061748;4391.714538554984;4451.554844515523;4465.843695470643;4496.145732310851;1374.0725010617793;1394.986050139506;1396.4204854985271;1402.8246469191351;1416.157386355117;1040.8022475729588;1046.7069220618087;1047.4553232319151;1048.2819268800895;1059.163261023798;6477.870442591122;6551.344865513411;6660.933390666129;6698.39714425704;6799.388055074968;1039.719275795531;1106.8727096383857;1148.9885101149048;1164.7030007348071;1183.5854856093472;4548.704334218265;4601.217792975202;4679.391679081766;4729.881752956135;4902.754862256873;11206.271592346471;12045.698857528632;12098.63704088861;12175.817362739566;12194.573189938486;11388.259763115371;12001.039916806634;12022.699432514257;12023.63929082129;12026.4588093536;8742.475451472932;8847.73456796297;8859.51272679996;8963.193312601898;8975.147361000825;6551.246606640298;6583.835404114935;6711.496637752286;6718.798436046926;6794.830129246809;10962.397068161328;11174.497147628213;11196.272242304223;11198.832017519906;11216.439178041228;10963.725906852074;11101.50043248072;11116.777664446505;11154.776904462027;11199.272047316897;7795.142534321107;8350.87473687883;8479.703210387606;8697.95624525061;8854.380193386402;9517.095875891779;9576.56905439243;9597.664081757104;9607.51962401891;9611.183049440597;8425.157484251526;8517.23344898962;8518.78703032429;8627.051024387187;8638.222559969632;8012.639431225492;8299.336053115858;8310.916890831257;8399.41204115706;8470.788230294293;7731.304182623591;7779.57212353326;7854.4894581851995;7855.685772569262;7869.0950540688555;5692.743826527899;5738.225339579103;5785.363610002981;5818.796342128006;5946.226990491382;5658.122990936411;5689.886202276014;5710.114932185518;5747.224124743107;5842.269716285465 00:23:29 INFO - PROCESS | 10854 | |4;prototype.html;355.33552257278893;359.67269784496096;362.69533591782897;365.6142769378305;370.5904975002622;5469.453054694529;5552.472515111063;5579.525740312073;5624.3813180550105;5631.436856314366;5347.770012897036;5490.81947381314;5510.5397069776545;5535.750891209896;5599.580031497633;4304.892377690559;4357.281048626973;4392.736435813846;4413.3159360299205;4416.5583441655735;4365.297533961758;4381.9561804381865;4387.451568553931;4388.714733542325;4421.602055814976;4126.8761937141935;4152.647025002879;4352.564743525654;4354.433923589929;4371.27873900806;4316.20150272199;4322.157179350028;4339.349097635349;4342.1532801103795;4344.761038142901;3873.985527525699;3875.399313106473;3880.9715425412287;3884.9805750971345;3897.279003384355;5101.984716037145;5279.841604751864;5408.053590621665;5420.100246368174;5423.918641220384;4981.562721722;4997.775100120503;5003.199488081889;5021.774020169065;5023.171176755831;5810.82567522975;5812.837432513515;5841.299044114733;5853.85365365865;5878.588498805086;4178.874633760992;4253.3194688849635;4278.9944363074965;4301;4331.7617531035785;5969.850753731148;5980.55145864062;5980.671063091571;5982.491488223506;5990.161377407167;4367.2575662137515;4401.801918913657;4416.6908316418185;4418.270985287391;4447.844325448594;4368.737875727466;4374.212641724456;4400.73595584266;4454.37638730578;4494.393256910341;4926.802927882916;4928.408590969107;4945.887175385581;4961.7519124044375;4973.403191617029;5796.971015144982;5823.912641310384;5853.122031695195;5861.472467477947;5862.472377486047;1481;1532.777747226646;1533.8082739657543;1535.7389243828577;1543.0433131458344;972.5866506734609;986.8273052215834;991.7619771254847;1004.5027711282962;1014.9238807089498;4966.478519755374;4982.60139188864;4983.775730092155;4989.875253118701;5047.318611987408;5191.766370513336;5232.0438951992;5232.555232805178;5247.1866860636665;5283.675182919503;5017.799288028511;5030.89938201234;5158.8392611662075;5188.625014371195;5427.267318911975;3110.315730539278;3120.2667373167287;3120.7035331643456;3126.2028182813237;3126.9530957035663;6043.909341359883;6093.6039157454625;6101.359357267423;6108.8778224436155;6122;5744.195812586241;5946;5980.850478738066;6005.969970150121;6068.787592434243;6040.879182416327;6043.884640375833;6058.57589968698;6062.120992456069;6101.786437474667;5707.600467967202;5895.469407753323;5929.318128415115;5938.782549577412;5946.732397042057;5875.823725288248;5880.705964701833;5889.676067816311;5899.528037757055;5909.2908850938165;6007.879842403215;6041.879162416815;6126.969365153146;6136.907946380718;6158.661273629993;4156.854510092132;4156.9192010076995;4248.468941382327;4283.22901877665;4290.2921018031675;5959.22530071088;5963.135345374983;5963.970180149072;5978.940210597838;6006.219191505076;5600.551955843604;5879.911801323069;5886.440788125121;5894;5907.4683278505145;5717.771289148387;5755.769769209185;5761.452661997104;5767.250257466502;5777.768889244383;5529.806456773994;5714.771409143754;5717.942820571741;5730.799422020376;5789.784145329528;5694.544436445157;5817.883642327214;5819.79630712923;5836.358000620014;5846.736896839738;5694.86442584031;5701.40135285789;5703.233931543115;5709.7145142741865;5741.5119714824705 00:23:29 INFO - PROCESS | 10854 | |5;yui.html;790.5691398187989;822.9300509456694;827.3298628111227;832.671094917508;935.5556110847352;15958.202089895502;18261.086945652696;18438;18508.537286567844;18542.629147417043;15890.84109158908;16328.101954392534;16568.337266509327;16590;16594;901.2159421303459;927.8886533615976;929.2380248196481;929.7117893453051;929.8233335666235;917.9632814687404;920.7421921861855;922.0134456131941;922.4419226368051;922.6263363337825;969.9515024248759;970.8495183246608;971.3637567393333;971.7230589282055;971.8493633486786;962.1484985787629;963.3978763272954;964.0167029629738;964.1659964131046;965.0879918477042;712.6401167410424;712.975045873392;713.2083387439937;713.2510863593207;713.4684659928387;15111.924440377838;15317;15355.385784568716;15356.155411452366;15374.308156132889;14342.211178385185;14603.70792584153;14662.926685366612;14812.407503699838;15420;13817.861821381757;13849.37677804491;13876.375563099684;14106.71786564263;14402.855971440362;4774.450938142123;4968.329275547827;4978.5270399312;5089.9745501272255;5098.515641014098;55480.72259638716;69547.65226173837;72180.6390968047;72571.27428725698;88210;12594.559190428292;12964.80552791709;13129.277889715966;13162.14446061003;13416.932915335556;22801.31596052121;22870.199543015915;22884.885575572014;22909.541809163722;22922.885385572965;7545.320921117126;8692.391532592657;9283.90716092844;9618.374805637752;9680;5159.329287192641;5278.313819203556;5453.890922181534;5470.781168753285;5574.247476590608;3882.9611703883165;4094.28350038742;4120.773357465308;4125.092479654471;4130.710850240514;683.54885775389;691.9550229235184;697.9197392299849;698.993010069903;706.3148745716646;796.6534557467404;814.7800093974594;815.5392203405057;816.1675091406731;829.5810615639064;10151.238657100746;10583.153347732163;10848.67453976382;10907.83638245427;11628.360440175702;806.7458750493576;810.3193317613233;857;860.5912191709014;882.4176043811182;4979.601631869442;5074.873128171826;5112.974435127875;5156.845294641167;5273.551748101378;112158.87841121647;115741;119364.40317798355;124260.37869810592;124934.37532812278;98529;103127.48436257771;106559;106562.46718766357;106676.46661766642;60512.697436512535;62434.37565624285;78728.606356969;81545.59227203827;91578.54210728903;10627.840582391425;11069.446527673745;11164.609238676607;11190.384528850991;11295.435228238719;50306;51092.74453627708;52050.47949520456;55611;55964;64890.93499945201;65780;77422;78378.6081069591;79159.60420198092;13718.24549649739;13920.182077492147;13933.23367214813;14177;14195.432182712575;15471.226438677795;15761.68476630484;15931.442399515961;15943.36226550925;16152.596185095466;15229.923850380677;15528.922355388151;15828;16568.420105296256;16596.50210493687;14451.92774036123;14869.405223790927;15216.391344346102;15552.84447155514;15610.45363412275;14725.705485890436;14789.482368117064;14936.701265974836;15114.622134446727;15320.157391343582;11923.880761192624;12335.198212116542;12514.937425312815;13029.47882084706;13176.538821141214;11916.033854413925;12700.809487857505;12811.743765124831;12962.93518532439;13142.474301027853 00:23:29 INFO - PROCESS | 10854 | __end_tp_report 00:23:29 INFO - PROCESS | 10854 | __start_cc_report 00:23:29 INFO - PROCESS | 10854 | _x_x_mozilla_cycle_collect,921 00:23:29 INFO - PROCESS | 10854 | __end_cc_report 00:23:29 INFO - PROCESS | 10854 | __startTimestamp1472628209449__endTimestamp 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | ------- Summary: start ------- 00:23:29 INFO - PROCESS | 10854 | Number of tests: 6 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | [#0] dojo.html Cycles:165 Average:5537.78 Median:5768.05 stddev:629.86 (10.9%) stddev-sans-first:604.45 00:23:29 INFO - PROCESS | 10854 | Values: 3197.7 3200.0 3201.6 3214.1 3261.4 5457.1 5580.0 5598.7 5621.5 5637.9 5513.9 5557.5 5586.1 5601.9 5638.6 4891.2 4952.2 5069.9 5088.7 5146.3 4960.3 4982.4 5039.1 5048.3 5063.3 5036.5 5077.0 5100.0 5126.2 5142.0 5179.0 5203.8 5205.5 5212.1 5228.8 4928.4 4959.4 4991.0 5085.7 5089.6 5420.6 5438.4 5469.5 5484.3 5493.4 6020.5 6027.9 6033.7 6037.0 6039.2 5836.2 5875.6 5876.3 5943.8 6046.9 5540.5 5592.5 5595.0 5597.4 5603.5 5774.9 6052.5 6081.7 6082.5 6088.5 5816.0 5852.5 5860.2 5938.4 5972.6 6068.0 6071.5 6074.5 6076.5 6079.2 5584.2 6003.2 6009.4 6013.5 6015.6 5816.1 5852.9 5853.7 5926.8 5952.2 4554.7 4562.5 4563.5 4566.7 4589.9 3984.2 4077.5 4082.1 4094.8 4118.9 5571.2 5602.6 5622.2 5624.9 5626.4 5960.5 5979.7 5999.6 6000.9 6029.4 5481.8 5525.9 5672.9 5678.7 5680.9 5109.4 5170.1 5178.9 5194.8 5202.8 6122.7 6138.0 6140.1 6142.4 6147.4 5695.3 5905.0 5952.0 5969.5 5972.0 6097.4 6177.7 6184.4 6188.3 6191.4 5899.2 5905.4 5906.8 5914.7 5919.5 5814.1 5904.4 5958.6 5985.8 6017.2 5836.2 5872.0 5896.0 5915.9 5940.2 5808.9 5818.4 5856.0 5859.2 5870.9 5633.1 5713.5 5793.8 5806.0 5808.4 5837.1 5929.7 5934.5 5936.3 5944.7 5722.6 5763.5 5764.7 5768.0 5781.9 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | [#1] ext.html Cycles:180 Average:68688.10 Median:22376.60 stddev:139972.37 (625.5%) stddev-sans-first:140271.79 00:23:29 INFO - PROCESS | 10854 | Values: 642.3 658.3 664.4 671.8 673.4 46034.8 48039.0 48156.3 52234.0 53007.0 49128.3 49450.5 49506.5 49580.8 49656.0 8461.2 9120.3 9216.0 9251.9 9259.0 10969.7 11219.9 11234.3 11608.7 11620.7 11355.7 11429.4 11439.6 11440.4 11441.1 11027.6 11171.8 11183.3 11230.3 11233.7 11034.9 11073.0 11104.0 11162.7 11188.6 45780.0 45920.0 46059.8 46067.8 46101.8 3888.8 3953.3 4115.0 4158.5 4622.0 32484.2 35105.5 35170.8 35589.8 37116.3 4447.7 4480.6 4492.9 4510.8 4544.3 394617.0 397328.0 398976.0 400082.0 400856.0 64998.0 65550.3 65563.3 65656.7 65786.0 39656.2 41175.2 41280.4 66702.0 67079.0 19741.0 19957.7 19988.0 19988.5 19991.8 14033.6 14288.6 14314.4 15374.2 15768.9 20932.7 20945.4 21057.2 21179.4 21230.0 7573.5 7750.4 7766.6 7902.3 7904.7 1037.3 1067.5 1138.4 1179.9 1257.5 34911.5 34949.5 35036.0 35145.0 35619.5 1139.6 1169.9 1170.4 1178.7 1212.5 18368.0 18656.3 18667.0 18710.1 18711.2 625064.0 628154.0 630577.0 632431.0 633123.0 42381.7 42670.8 42671.0 42713.8 42773.4 1328.7 1448.6 1450.7 1480.0 1486.0 33809.3 36132.0 36176.8 36519.8 36571.8 53686.5 61919.0 63373.0 63499.4 69923.7 524843.0 525440.0 525636.0 527965.0 528740.0 31415.8 31484.0 31742.5 33493.5 50593.0 51693.2 52229.2 52376.7 52639.5 52999.0 44187.8 44491.3 44884.8 45223.1 45245.5 45174.5 45446.3 45682.0 46478.8 46537.8 45283.1 45828.0 46162.0 46177.0 46270.1 38642.6 40437.6 40502.4 40638.2 41481.0 30569.2 30584.4 30610.5 30963.8 31190.2 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | [#2] jquery.html Cycles:180 Average:31214.03 Median:24178.88 stddev:91779.17 (379.6%) stddev-sans-first:92007.48 00:23:29 INFO - PROCESS | 10854 | Values: 401.9 410.7 414.6 417.7 450.8 15066.6 19846.6 20280.6 20428.3 20641.4 19532.3 19587.0 19593.7 19626.3 19819.0 1920.8 1921.1 2074.8 4382.2 6359.2 6541.6 6629.8 6669.3 6721.5 6768.7 6541.5 6601.3 6633.1 6640.0 6656.0 6432.9 6653.1 6684.0 6722.8 6730.2 5958.3 5964.1 5966.8 5973.5 5975.5 24101.0 24256.8 24260.8 24377.9 24495.8 2541.5 2812.3 2852.0 2876.7 3272.5 10927.9 11260.7 11401.7 14993.5 15322.3 2025.0 2103.7 2105.3 2136.4 2137.4 57596.4 73354.0 74527.0 83899.0 87199.6 11262.9 11339.6 11351.8 11511.9 11946.5 16377.9 16391.3 16399.3 16406.3 16434.8 8587.9 8666.2 8676.4 8829.9 8848.1 8731.4 8854.6 8977.6 9086.0 9161.8 4865.7 4995.4 5002.0 5047.5 5090.2 2078.3 2083.8 2096.9 2116.9 2127.7 952.3 1035.7 1057.0 1061.3 1094.3 20439.7 20720.7 20762.8 20947.6 21320.6 1007.4 1032.2 1040.4 1043.9 1105.8 9188.4 10977.1 11158.4 11253.5 11255.7 523454.0 563387.0 563944.0 571100.0 574916.0 72415.0 82804.2 84525.6 93990.0 94966.0 1057.7 1058.8 1097.8 1106.3 1108.3 18119.3 18441.7 18448.7 18458.0 18878.7 45219.3 45607.0 45656.0 46689.5 47527.5 62005.0 62353.0 63457.0 63985.0 64563.7 8079.2 8203.4 8445.0 8484.3 8643.6 13206.5 13535.3 13833.1 13900.4 13937.4 11435.0 11604.1 11610.5 12121.5 12251.4 11563.2 11755.9 12056.6 12286.8 12326.2 11376.4 11587.7 12129.2 12333.1 12909.8 8253.8 8327.4 8416.6 8423.2 8655.3 9041.8 11531.1 11539.4 11543.1 11560.6 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | [#3] mootools.html Cycles:180 Average:6362.60 Median:4574.96 stddev:3611.31 (78.9%) stddev-sans-first:3602.64 00:23:29 INFO - PROCESS | 10854 | Values: 1458.6 1568.4 1705.1 1782.9 1906.1 9156.2 9840.7 9845.2 9852.7 9853.3 8213.3 9102.5 9350.2 9355.8 9560.1 2524.8 2528.1 2568.5 2569.7 2577.6 2385.5 2471.6 2472.9 2473.0 2473.2 2496.1 2498.1 2573.7 2576.7 2577.7 2450.6 2461.1 2467.7 2522.4 2527.5 2467.8 2475.8 2482.4 2486.3 2503.2 9641.1 9751.6 9787.0 9850.4 9851.3 5659.5 5792.6 5803.7 5821.5 5824.1 3418.8 3531.9 3618.4 3623.3 3634.5 1099.5 1108.7 1112.4 1125.0 1174.8 13380.2 13391.9 13402.4 13422.5 13431.7 4855.1 5057.3 5193.8 5217.5 5230.8 11234.2 11242.0 11245.8 11261.0 11266.5 5776.8 5780.3 5781.1 5789.5 5790.5 4602.0 4707.1 5261.4 5281.7 5319.7 4384.6 4391.7 4451.6 4465.8 4496.1 1374.1 1395.0 1396.4 1402.8 1416.2 1040.8 1046.7 1047.5 1048.3 1059.2 6477.9 6551.3 6660.9 6698.4 6799.4 1039.7 1106.9 1149.0 1164.7 1183.6 4548.7 4601.2 4679.4 4729.9 4902.8 11206.3 12045.7 12098.6 12175.8 12194.6 11388.3 12001.0 12022.7 12023.6 12026.5 8742.5 8847.7 8859.5 8963.2 8975.1 6551.2 6583.8 6711.5 6718.8 6794.8 10962.4 11174.5 11196.3 11198.8 11216.4 10963.7 11101.5 11116.8 11154.8 11199.3 7795.1 8350.9 8479.7 8698.0 8854.4 9517.1 9576.6 9597.7 9607.5 9611.2 8425.2 8517.2 8518.8 8627.1 8638.2 8012.6 8299.3 8310.9 8399.4 8470.8 7731.3 7779.6 7854.5 7855.7 7869.1 5692.7 5738.2 5785.4 5818.8 5946.2 5658.1 5689.9 5710.1 5747.2 5842.3 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | [#4] prototype.html Cycles:180 Average:4853.67 Median:5422.01 stddev:1401.31 (25.8%) stddev-sans-first:1363.96 00:23:29 INFO - PROCESS | 10854 | Values: 355.3 359.7 362.7 365.6 370.6 5469.5 5552.5 5579.5 5624.4 5631.4 5347.8 5490.8 5510.5 5535.8 5599.6 4304.9 4357.3 4392.7 4413.3 4416.6 4365.3 4382.0 4387.5 4388.7 4421.6 4126.9 4152.6 4352.6 4354.4 4371.3 4316.2 4322.2 4339.3 4342.2 4344.8 3874.0 3875.4 3881.0 3885.0 3897.3 5102.0 5279.8 5408.1 5420.1 5423.9 4981.6 4997.8 5003.2 5021.8 5023.2 5810.8 5812.8 5841.3 5853.9 5878.6 4178.9 4253.3 4279.0 4301.0 4331.8 5969.9 5980.6 5980.7 5982.5 5990.2 4367.3 4401.8 4416.7 4418.3 4447.8 4368.7 4374.2 4400.7 4454.4 4494.4 4926.8 4928.4 4945.9 4961.8 4973.4 5797.0 5823.9 5853.1 5861.5 5862.5 1481.0 1532.8 1533.8 1535.7 1543.0 972.6 986.8 991.8 1004.5 1014.9 4966.5 4982.6 4983.8 4989.9 5047.3 5191.8 5232.0 5232.6 5247.2 5283.7 5017.8 5030.9 5158.8 5188.6 5427.3 3110.3 3120.3 3120.7 3126.2 3127.0 6043.9 6093.6 6101.4 6108.9 6122.0 5744.2 5946.0 5980.9 6006.0 6068.8 6040.9 6043.9 6058.6 6062.1 6101.8 5707.6 5895.5 5929.3 5938.8 5946.7 5875.8 5880.7 5889.7 5899.5 5909.3 6007.9 6041.9 6127.0 6136.9 6158.7 4156.9 4156.9 4248.5 4283.2 4290.3 5959.2 5963.1 5964.0 5978.9 6006.2 5600.6 5879.9 5886.4 5894.0 5907.5 5717.8 5755.8 5761.5 5767.3 5777.8 5529.8 5714.8 5717.9 5730.8 5789.8 5694.5 5817.9 5819.8 5836.4 5846.7 5694.9 5701.4 5703.2 5709.7 5741.5 00:23:29 INFO - PROCESS | 10854 | 00:23:29 INFO - PROCESS | 10854 | [#5] yui.html Cycles:180 Average:21387.16 Median:4107.53 stddev:29759.68 (724.5%) stddev-sans-first:29802.98 00:23:29 INFO - PROCESS | 10854 | Values: 790.6 822.9 827.3 832.7 935.6 15958.2 18261.1 18438.0 18508.5 18542.6 15890.8 16328.1 16568.3 16590.0 16594.0 901.2 927.9 929.2 929.7 929.8 918.0 920.7 922.0 922.4 922.6 970.0 970.8 971.4 971.7 971.8 962.1 963.4 964.0 964.2 965.1 712.6 713.0 713.2 713.3 713.5 15111.9 15317.0 15355.4 15356.2 15374.3 14342.2 14603.7 14662.9 14812.4 15420.0 13817.9 13849.4 13876.4 14106.7 14402.9 4774.5 4968.3 4978.5 5090.0 5098.5 55480.7 69547.7 72180.6 72571.3 88210.0 12594.6 12964.8 13129.3 13162.1 13416.9 22801.3 22870.2 22884.9 22909.5 22922.9 7545.3 8692.4 9283.9 9618.4 9680.0 5159.3 5278.3 5453.9 5470.8 5574.2 3883.0 4094.3 4120.8 4125.1 4130.7 683.5 692.0 697.9 699.0 706.3 796.7 814.8 815.5 816.2 829.6 10151.2 10583.2 10848.7 10907.8 11628.4 806.7 810.3 857.0 860.6 882.4 4979.6 5074.9 5113.0 5156.8 5273.6 112158.9 115741.0 119364.4 124260.4 124934.4 98529.0 103127.5 106559.0 106562.5 106676.5 60512.7 62434.4 78728.6 81545.6 91578.5 10627.8 11069.4 11164.6 11190.4 11295.4 50306.0 51092.7 52050.5 55611.0 55964.0 64890.9 65780.0 77422.0 78378.6 79159.6 13718.2 13920.2 13933.2 14177.0 14195.4 15471.2 15761.7 15931.4 15943.4 16152.6 15229.9 15528.9 15828.0 16568.4 16596.5 14451.9 14869.4 15216.4 15552.8 15610.5 14725.7 14789.5 14936.7 15114.6 15320.2 11923.9 12335.2 12514.9 13029.5 13176.5 11916.0 12700.8 12811.7 12962.9 13142.5 00:23:29 INFO - PROCESS | 10854 | -------- Summary: end -------- 00:23:29 INFO - PROCESS | 10854 | 00:23:30 INFO - TEST-INFO | 10854: exit 0 00:23:30 INFO - TEST-OK | dromaeo_css | took 1112642ms 00:23:30 INFO - TEST-START | kraken 00:23:30 INFO - operating with platform_type : linux_ 00:23:30 INFO - Initialising browser for kraken test... 00:23:30 INFO - TEST-INFO | started process 11059 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjaO8xL/profile http://localhost:39564/getInfo.html) 00:23:33 INFO - PROCESS | 11059 | __metrics Screen width/height:1600/1200 00:23:33 INFO - PROCESS | 11059 | colorDepth:24 00:23:33 INFO - PROCESS | 11059 | Browser inner width/height: 1024/697 00:23:33 INFO - PROCESS | 11059 | __metrics 00:23:34 INFO - PROCESS | 11059 | 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" 00:23:34 INFO - PROCESS | 11059 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:23:35 INFO - TEST-INFO | 11059: exit 0 00:23:35 INFO - Browser initialized. 00:23:35 INFO - Running cycle 1/1 for kraken test... 00:23:35 INFO - TEST-INFO | started process 11146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjaO8xL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:23:47 INFO - PROCESS | 11146 | RSS: Main: 145219584 00:23:47 INFO - PROCESS | 11146 | 00:23:49 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39564/tests/kraken/driver.html?testName=audio-beat-detection) 00:23:50 INFO - PROCESS | 11146 | RSS: Main: 191700992 00:23:50 INFO - PROCESS | 11146 | 00:23:52 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39564/tests/kraken/driver.html?testName=audio-dft) 00:23:53 INFO - PROCESS | 11146 | RSS: Main: 181960704 00:23:53 INFO - PROCESS | 11146 | 00:23:56 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39564/tests/kraken/driver.html?testName=audio-fft) 00:23:56 INFO - PROCESS | 11146 | RSS: Main: 269803520 00:23:56 INFO - PROCESS | 11146 | 00:23:59 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39564/tests/kraken/driver.html?testName=audio-oscillator) 00:23:59 INFO - PROCESS | 11146 | RSS: Main: 175325184 00:23:59 INFO - PROCESS | 11146 | 00:24:01 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39564/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:24:02 INFO - PROCESS | 11146 | RSS: Main: 176967680 00:24:02 INFO - PROCESS | 11146 | 00:24:05 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39564/tests/kraken/driver.html?testName=imaging-darkroom) 00:24:05 INFO - PROCESS | 11146 | RSS: Main: 212791296 00:24:05 INFO - PROCESS | 11146 | 00:24:09 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39564/tests/kraken/driver.html?testName=imaging-desaturate) 00:24:09 INFO - PROCESS | 11146 | RSS: Main: 211849216 00:24:09 INFO - PROCESS | 11146 | 00:24:12 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39564/tests/kraken/driver.html?testName=json-parse-financial) 00:24:13 INFO - PROCESS | 11146 | RSS: Main: 211664896 00:24:13 INFO - PROCESS | 11146 | 00:24:14 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39564/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:24:15 INFO - PROCESS | 11146 | RSS: Main: 176484352 00:24:15 INFO - PROCESS | 11146 | 00:24:17 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:24:17 INFO - PROCESS | 11146 | RSS: Main: 180920320 00:24:17 INFO - PROCESS | 11146 | 00:24:19 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:24:20 INFO - PROCESS | 11146 | RSS: Main: 185036800 00:24:20 INFO - PROCESS | 11146 | 00:24:22 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:24:23 INFO - PROCESS | 11146 | RSS: Main: 181809152 00:24:23 INFO - PROCESS | 11146 | 00:24:25 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:24:26 INFO - PROCESS | 11146 | RSS: Main: 183754752 00:24:26 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | Cycle 1(1): loaded http://localhost:39564/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39564/tests/kraken/driver.html?testName=ai-astar) 00:24:28 INFO - PROCESS | 11146 | RSS: Main: 235429888 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | __start_tp_report 00:24:28 INFO - PROCESS | 11146 | _x_x_mozilla_page_load 00:24:28 INFO - PROCESS | 11146 | _x_x_mozilla_page_load_details 00:24:28 INFO - PROCESS | 11146 | |i|pagename|runs| 00:24:28 INFO - PROCESS | 11146 | |0;ai-astar;102;111;113;112;111;112;109;110;111;109 00:24:28 INFO - PROCESS | 11146 | |1;audio-beat-detection;141;115;116;183;116;115;148;119;151;117 00:24:28 INFO - PROCESS | 11146 | |2;audio-dft;161;160;160;161;160;160;160;160;158;162 00:24:28 INFO - PROCESS | 11146 | |3;audio-fft;76;75;74;111;75;76;75;75;109;74 00:24:28 INFO - PROCESS | 11146 | |4;audio-oscillator;84;83;140;84;84;83;116;83;84;118 00:24:28 INFO - PROCESS | 11146 | |5;imaging-gaussian-blur;117;116;116;116;117;117;116;117;116;117 00:24:28 INFO - PROCESS | 11146 | |6;imaging-darkroom;145;146;145;145;146;147;145;146;146;146 00:24:28 INFO - PROCESS | 11146 | |7;imaging-desaturate;95;95;96;95;95;94;95;95;95;94 00:24:28 INFO - PROCESS | 11146 | |8;json-parse-financial;50;48;50;50;49;50;51;49;49;49 00:24:28 INFO - PROCESS | 11146 | |9;json-stringify-tinderbox;60;61;60;60;61;60;60;61;61;61 00:24:28 INFO - PROCESS | 11146 | |10;stanford-crypto-aes;72;75;72;75;73;71;73;72;75;72 00:24:28 INFO - PROCESS | 11146 | |11;stanford-crypto-ccm;118;116;117;116;116;115;126;117;147;117 00:24:28 INFO - PROCESS | 11146 | |12;stanford-crypto-pbkdf2;150;152;152;159;151;157;152;152;152;153 00:24:28 INFO - PROCESS | 11146 | |13;stanford-crypto-sha256-iterative;61;61;63;61;63;61;60;60;60;59 00:24:28 INFO - PROCESS | 11146 | __end_tp_report 00:24:28 INFO - PROCESS | 11146 | __start_cc_report 00:24:28 INFO - PROCESS | 11146 | _x_x_mozilla_cycle_collect,1623 00:24:28 INFO - PROCESS | 11146 | __end_cc_report 00:24:28 INFO - PROCESS | 11146 | __startTimestamp1472628268369__endTimestamp 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | ------- Summary: start ------- 00:24:28 INFO - PROCESS | 11146 | Number of tests: 14 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:3.09 (2.8%) stddev-sans-first:1.36 00:24:28 INFO - PROCESS | 11146 | Values: 102.0 111.0 113.0 112.0 111.0 112.0 109.0 110.0 111.0 109.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:130.00 stddev:23.05 (17.7%) stddev-sans-first:24.23 00:24:28 INFO - PROCESS | 11146 | Values: 141.0 115.0 116.0 183.0 116.0 115.0 148.0 119.0 151.0 117.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 00:24:28 INFO - PROCESS | 11146 | Values: 161.0 160.0 160.0 161.0 160.0 160.0 160.0 160.0 158.0 162.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:14.78 (19.7%) stddev-sans-first:15.52 00:24:28 INFO - PROCESS | 11146 | Values: 76.0 75.0 74.0 111.0 75.0 76.0 75.0 75.0 109.0 74.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:83.50 stddev:20.82 (24.9%) stddev-sans-first:21.64 00:24:28 INFO - PROCESS | 11146 | Values: 84.0 83.0 140.0 84.0 84.0 83.0 116.0 83.0 84.0 118.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 00:24:28 INFO - PROCESS | 11146 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 00:24:28 INFO - PROCESS | 11146 | Values: 145.0 146.0 145.0 145.0 146.0 147.0 145.0 146.0 146.0 146.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 00:24:28 INFO - PROCESS | 11146 | Values: 95.0 95.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:0.85 (1.7%) stddev-sans-first:0.88 00:24:28 INFO - PROCESS | 11146 | Values: 50.0 48.0 50.0 50.0 49.0 50.0 51.0 49.0 49.0 49.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 00:24:28 INFO - PROCESS | 11146 | Values: 60.0 61.0 60.0 60.0 61.0 60.0 60.0 61.0 61.0 61.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:1.49 (2.0%) stddev-sans-first:1.54 00:24:28 INFO - PROCESS | 11146 | Values: 72.0 75.0 72.0 75.0 73.0 71.0 73.0 72.0 75.0 72.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#11] stanford-crypto-ccm Cycles:10 Average:120.50 Median:117.00 stddev:9.81 (8.4%) stddev-sans-first:10.37 00:24:28 INFO - PROCESS | 11146 | Values: 118.0 116.0 117.0 116.0 116.0 115.0 126.0 117.0 147.0 117.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:152.00 stddev:2.79 (1.8%) stddev-sans-first:2.74 00:24:28 INFO - PROCESS | 11146 | Values: 150.0 152.0 152.0 159.0 151.0 157.0 152.0 152.0 152.0 153.0 00:24:28 INFO - PROCESS | 11146 | 00:24:28 INFO - PROCESS | 11146 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.29 (2.1%) stddev-sans-first:1.36 00:24:28 INFO - PROCESS | 11146 | Values: 61.0 61.0 63.0 61.0 63.0 61.0 60.0 60.0 60.0 59.0 00:24:28 INFO - PROCESS | 11146 | -------- Summary: end -------- 00:24:28 INFO - PROCESS | 11146 | 00:24:29 INFO - TEST-INFO | 11146: exit 0 00:24:29 INFO - TEST-OK | kraken | took 59182ms 00:24:29 INFO - SUITE-END | took 1171s 00:24:29 INFO - Completed test suite (00:19:32) 00:24:29 INFO - javascript benchmark 00:24:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3197.6642452542483, 3199.9520007199894, 3201.615806103268, 3214.148250713563, 3261.445554255776, 5457.099578569536, 5580.023495888224, 5598.748056337465, 5621.4940655341115, 5637.91543126853, 5513.917291240625, 5557.527610153132, 5586.078297080977, 5601.859953501154, 5638.605297629168, 4891.241857512091, 4952.158133117378, 5069.923951140736, 5088.720120393377, 5146.305248791403, 4960.305557221992, 4982.352294201749, 5039.118154322997, 5048.29323894655, 5063.265826455162, 5036.471170527078, 5077.0, 5099.974500127512, 5126.231065340192, 5142.04872098663, 5179.0, 5203.817866374697, 5205.531502164805, 5212.087884620214, 5228.790848366079, 4928.383952005999, 4959.380077490314, 4991.0, 5085.694858308476, 5089.618278629117, 5420.647657902224, 5438.401775804658, 5469.480399362054, 5484.314460692413, 5493.423190564973, 6020.548458865603, 6027.939720602825, 6033.6983150842725, 6037.003894357425, 6039.245094363205, 5836.182934389189, 5875.559333050039, 5876.29484461863, 5943.762249510014, 6046.939530604682, 5540.473655002768, 5592.496675299202, 5595.0, 5597.44025597441, 5603.467670571371, 5774.94225057744, 6052.513576802334, 6081.726322315506, 6082.513398928075, 6088.4824789893355, 5815.9709201453725, 5852.473277404969, 5860.150278209636, 5938.376470470625, 5972.611780234271, 6067.998780201306, 6071.51427885768, 6074.51403887688, 6076.4531192192035, 6079.240094988127, 5584.246126772915, 6003.159557661931, 6009.399060094043, 6013.488853447506, 6015.639061656227, 5816.069428891442, 5852.941470585325, 5853.7365818538265, 5926.762929482772, 5952.226210592682, 4554.658400619966, 4562.543745625411, 4563.452385713736, 4566.725996440225, 4589.885252868638, 3984.183242435352, 4077.510698716114, 4082.0611259410502, 4094.7952602369764, 4118.91762164761, 5571.247881536022, 5602.607817452739, 5622.212890195376, 5624.91562626561, 5626.409227031185, 5960.4635582797755, 5979.701014949235, 5999.580029397987, 6000.909986350208, 6029.3970602940235, 5481.780728770805, 5525.944740552623, 5672.943270567324, 5678.659280443187, 5680.8863822723315, 5109.3613298337705, 5170.095233334227, 5178.948210517847, 5194.844154675366, 5202.817901373433, 6122.724477398528, 6138.0, 6140.109684095781, 6142.385761423911, 6147.354527774609, 5695.316562012419, 5904.996150654485, 5952.047672372572, 5969.522438204846, 5972.014617588049, 6097.420745029215, 6177.722002509988, 6184.350643182581, 6188.257409110935, 6191.442770150708, 5899.174115623902, 5905.379935106924, 5906.822795316148, 5914.67469289176, 5919.467247947706, 5814.127880817911, 5904.380040095728, 5958.553108516792, 5985.820425387245, 6017.15759793638, 5836.241288632451, 5872.031114865999, 5896.0, 5915.940840591539, 5940.198073260054, 5808.912866306924, 5818.44724751148, 5856.0, 5859.20900678403, 5870.882582348244, 5633.126865335879, 5713.542916566747, 5793.768249270151, 5806.042003069446, 5808.39011903744, 5837.09525023622, 5929.733162007807, 5934.525237981037, 5936.287645482397, 5944.702764861826, 5722.599418040863, 5763.4812866842185, 5764.7405866736935, 5768.048272035067, 5781.855453613525], "value": 5495.163489100442, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.280645676842, 658.2890478283455, 664.3721683009558, 671.7783131566581, 673.4141297071549, 46034.76982615086, 48038.999999999956, 48156.27765583515, 52234.0, 53007.0, 49128.26307605389, 49450.50549494504, 49506.50493495064, 49580.75209623956, 49656.0, 8461.15388461155, 9120.270378369714, 9215.95392023039, 9251.861222081674, 9259.0, 10969.725756856064, 11219.943900280487, 11234.269772464804, 11608.709782255426, 11620.709482262926, 11355.716107097305, 11429.42852857358, 11439.59961401351, 11440.427978601077, 11441.141914356456, 11027.558897644085, 11171.832422513588, 11183.273087249343, 11230.270032447947, 11233.66299011027, 11034.889651103547, 11073.0, 11104.0, 11162.665120046411, 11188.608398706006, 45780.0, 45920.0, 46059.769701151614, 46067.76966115148, 46101.76949115266, 3888.766673999569, 3953.2686453006286, 4115.012397024722, 4158.521769996459, 4621.953780462186, 32484.187895302806, 35105.473417898495, 35170.82414587911, 35589.82205088958, 37116.257674846354, 4447.666425018137, 4480.596746292849, 4492.887677808081, 4510.8421205257655, 4544.250198717207, 394617.0, 397328.0, 398976.0, 400082.0, 400856.0, 64998.0, 65550.34449655442, 65563.34436655667, 65656.67171664111, 65786.0, 39656.20687586232, 41175.1764964699, 41280.38079428811, 66702.0, 67079.0, 19741.0, 19957.700634490495, 19988.0, 19988.50028749293, 19991.800081999285, 14033.649158770908, 14288.571342859523, 14314.355853986595, 15374.231288435534, 15768.921155394379, 20932.686009709865, 20945.37163885086, 21057.157713691588, 21179.364619061453, 21230.0, 7573.471365902922, 7750.418718596128, 7766.611669416619, 7902.328302094272, 7904.6838126474295, 1037.273908264218, 1067.4716015572187, 1138.4137169357787, 1179.899708524785, 1257.4592925042136, 34911.4763278551, 34949.47575786365, 35036.0, 35145.0, 35619.4657080144, 1139.5897476908306, 1169.9415029248673, 1170.3504554972071, 1178.6640807369859, 1212.4665147335145, 18368.0, 18656.34702785396, 18666.973316467724, 18710.064496775107, 18711.157997889855, 625064.0, 628154.0, 630577.0, 632431.0, 633123.0, 42381.728548143605, 42670.786646067194, 42671.0, 42713.786431067645, 42773.358399624034, 1328.6744747536916, 1448.608875603581, 1450.6663467402557, 1480.0, 1486.041503230432, 33809.32381352408, 36132.0, 36176.819115904254, 36519.81740091283, 36571.81714091413, 53686.46313536815, 61919.0, 63373.0, 63499.365006351196, 69923.65038174776, 524843.0, 525440.0, 525636.0, 527965.0, 528740.0, 31415.84292078525, 31484.0, 31742.52386214255, 33493.497597536545, 50593.0, 51693.22460163176, 52229.216561752364, 52376.73811630918, 52639.473605263454, 52999.0, 44187.77906110449, 44491.33263000993, 44884.77557612191, 45223.0955380884, 45245.54754452413, 45174.54825451703, 45446.31830522611, 45682.0, 46478.76760616175, 46537.767311163225, 45283.09433811371, 45828.0, 46162.0, 46177.0, 46270.074598508516, 38642.6135738639, 40437.59562404338, 40502.392464113655, 40638.187236255704, 41481.0, 30569.23576910595, 30584.388312234074, 30610.540841886945, 30963.845180774853, 31190.22024449407], "value": 22391.22859647104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.923634509443, 410.71250124912564, 414.56263641857845, 417.6783876415158, 450.8219253394909, 15066.623334416645, 19846.60306793863, 20280.594388112248, 20428.28501002465, 20641.38075857723, 19532.31636892709, 19587.0, 19593.706094408597, 19626.313079042236, 19819.0, 1920.7695076590828, 1921.077882616345, 2074.8340132789376, 4382.167388196249, 6359.236891573018, 6541.607503549779, 6629.834254143637, 6669.299723529032, 6721.462283017347, 6768.695408706595, 6541.47668186544, 6601.339866013408, 6633.10453088834, 6639.966800166017, 6656.0348749431305, 6432.903506447407, 6653.0685704001, 6683.966580167093, 6722.798316050526, 6730.226024007228, 5958.344582095966, 5964.105384192406, 5966.761329546813, 5973.522118230531, 5975.52195824333, 24101.0, 24256.757432425627, 24260.757392426025, 24377.878110609334, 24495.755042449524, 2541.4789968056502, 2812.31098380898, 2851.9732896157366, 2876.7267109624554, 3272.4927636216426, 10927.945360273148, 11260.662180134608, 11401.657950261506, 14993.475228366955, 15322.310496027594, 2025.0, 2103.6739305407687, 2105.305249267738, 2136.412486566191, 2137.3801597536694, 57596.42403575994, 73354.0, 74527.0, 83899.0, 87199.56400218085, 11262.943685281522, 11339.603113890975, 11351.772964540829, 11511.884881151249, 11946.462409191607, 16377.91811040961, 16391.262393192334, 16399.262033208535, 16406.343746250015, 16434.83565164357, 8587.871181932276, 8666.17671321236, 8676.392652514263, 8829.867551986725, 8848.1151884811, 8731.388802783744, 8854.601542930584, 8977.596008179647, 9086.0, 9161.770955726028, 4865.732384718804, 4995.425526064477, 5002.0, 5047.495250474996, 5090.160123579568, 2078.262216912974, 2083.8020388063414, 2096.8637038592747, 2116.925907593227, 2127.6808478728312, 952.3476418653245, 1035.7410647338165, 1057.0169742139883, 1061.2571200159919, 1094.3379255550437, 20439.693404598944, 20720.689189662167, 20762.792372076387, 20947.581048378946, 21320.573588528143, 1007.3955626624113, 1032.1691038713907, 1040.38617215843, 1043.88517263101, 1105.7788442311569, 9188.448693078437, 10977.12183025356, 11158.442077896236, 11253.549858005752, 11255.718607034889, 523454.0, 563387.0, 563944.0, 571100.0, 574916.0, 72415.0, 82804.17195828086, 84525.57737211275, 93990.0, 94966.0, 1057.672121642278, 1058.804121237558, 1097.8023955687893, 1106.2753896198003, 1108.2740804772886, 18119.275228990693, 18441.72337414913, 18448.723269151244, 18457.984810835533, 18878.71681924745, 45219.32171017372, 45607.0, 45656.0, 46689.53310466852, 47527.52472475369, 62005.0, 62353.0, 63457.0, 63985.0, 64563.67718161379, 8079.192080791874, 8203.384746144182, 8444.957775211085, 8484.278836298983, 8643.611037503202, 13206.537771177962, 13535.25556094385, 13833.10084844482, 13900.443982241002, 13937.372818223406, 11435.028022618171, 11604.129690273094, 11610.477528511406, 12121.515139394678, 12251.448684809027, 11563.248388854698, 11755.941220294186, 12056.578019769266, 12286.754264914473, 12326.198797078161, 11376.374299413612, 11587.652370428901, 12129.150959432754, 12333.075019373404, 12909.806352904901, 8253.79365515867, 8327.37544684139, 8416.621252043551, 8423.157684231774, 8655.307575393828, 9041.77395565116, 11531.135164862837, 11539.36533490666, 11543.134264930331, 11560.595379161688], "value": 9624.805559507382, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.5988853065403, 1568.3726509396242, 1705.1474262868567, 1782.8662850286216, 1906.0660276464528, 9156.2217211537, 9840.704778856629, 9845.163161131313, 9852.655157069505, 9853.261005424594, 8213.301869341098, 9102.544872756353, 9350.158485736283, 9355.766105847357, 9560.139587437146, 2524.8358856674304, 2528.127795910408, 2568.4863027394504, 2569.717331093578, 2577.6133579962966, 2385.4751954569965, 2471.6292556116546, 2472.8763561821925, 2472.950540989179, 2473.2209354053507, 2496.1263557754824, 2498.0757119865675, 2573.7040240372407, 2576.7294434084433, 2577.716451190377, 2450.5588993981078, 2461.0770960889668, 2467.6545283660303, 2522.3946252899173, 2527.4565968316715, 2467.7778999889915, 2475.7771800537857, 2482.3918140055803, 2486.254123762882, 2503.173952595639, 9641.084097010773, 9751.561179746857, 9787.0, 9850.408975461496, 9851.261155413373, 5659.547236221134, 5792.565557583198, 5803.680797556132, 5821.476067153976, 5824.126381042835, 3418.8290585470877, 3531.94702079469, 3618.4210526315665, 3623.2572322673786, 3634.528520605987, 1099.4722533184035, 1108.700650824281, 1112.3603927741501, 1124.9775004499863, 1174.829649700797, 13380.197188168644, 13391.86608133916, 13402.396892139877, 13422.463101475929, 13431.66420839477, 4855.053264613437, 5057.266696329012, 5193.844184674465, 5217.50433708797, 5230.790768369223, 11234.213605047567, 11242.0, 11245.775084498264, 11261.0, 11266.493007814504, 5776.826695199151, 5780.335261444988, 5781.132830075522, 5789.507891829242, 5790.536757059425, 4601.994397311474, 4707.105649926503, 5261.421243663194, 5281.656692314988, 5319.7340132993195, 4384.649228061748, 4391.714538554984, 4451.554844515523, 4465.843695470643, 4496.145732310851, 1374.0725010617793, 1394.986050139506, 1396.4204854985271, 1402.8246469191351, 1416.157386355117, 1040.8022475729588, 1046.7069220618087, 1047.4553232319151, 1048.2819268800895, 1059.163261023798, 6477.870442591122, 6551.344865513411, 6660.933390666129, 6698.39714425704, 6799.388055074968, 1039.719275795531, 1106.8727096383857, 1148.9885101149048, 1164.7030007348071, 1183.5854856093472, 4548.704334218265, 4601.217792975202, 4679.391679081766, 4729.881752956135, 4902.754862256873, 11206.271592346471, 12045.698857528632, 12098.63704088861, 12175.817362739566, 12194.573189938486, 11388.259763115371, 12001.039916806634, 12022.699432514257, 12023.63929082129, 12026.4588093536, 8742.475451472932, 8847.73456796297, 8859.51272679996, 8963.193312601898, 8975.147361000825, 6551.246606640298, 6583.835404114935, 6711.496637752286, 6718.798436046926, 6794.830129246809, 10962.397068161328, 11174.497147628213, 11196.272242304223, 11198.832017519906, 11216.439178041228, 10963.725906852074, 11101.50043248072, 11116.777664446505, 11154.776904462027, 11199.272047316897, 7795.142534321107, 8350.87473687883, 8479.703210387606, 8697.95624525061, 8854.380193386402, 9517.095875891779, 9576.56905439243, 9597.664081757104, 9607.51962401891, 9611.183049440597, 8425.157484251526, 8517.23344898962, 8518.78703032429, 8627.051024387187, 8638.222559969632, 8012.639431225492, 8299.336053115858, 8310.916890831257, 8399.41204115706, 8470.788230294293, 7731.304182623591, 7779.57212353326, 7854.4894581851995, 7855.685772569262, 7869.0950540688555, 5692.743826527899, 5738.225339579103, 5785.363610002981, 5818.796342128006, 5946.226990491382, 5658.122990936411, 5689.886202276014, 5710.114932185518, 5747.224124743107, 5842.269716285465], "value": 5084.007097299108, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.33552257278893, 359.67269784496096, 362.69533591782897, 365.6142769378305, 370.5904975002622, 5469.453054694529, 5552.472515111063, 5579.525740312073, 5624.3813180550105, 5631.436856314366, 5347.770012897036, 5490.81947381314, 5510.5397069776545, 5535.750891209896, 5599.580031497633, 4304.892377690559, 4357.281048626973, 4392.736435813846, 4413.3159360299205, 4416.5583441655735, 4365.297533961758, 4381.9561804381865, 4387.451568553931, 4388.714733542325, 4421.602055814976, 4126.8761937141935, 4152.647025002879, 4352.564743525654, 4354.433923589929, 4371.27873900806, 4316.20150272199, 4322.157179350028, 4339.349097635349, 4342.1532801103795, 4344.761038142901, 3873.985527525699, 3875.399313106473, 3880.9715425412287, 3884.9805750971345, 3897.279003384355, 5101.984716037145, 5279.841604751864, 5408.053590621665, 5420.100246368174, 5423.918641220384, 4981.562721722, 4997.775100120503, 5003.199488081889, 5021.774020169065, 5023.171176755831, 5810.82567522975, 5812.837432513515, 5841.299044114733, 5853.85365365865, 5878.588498805086, 4178.874633760992, 4253.3194688849635, 4278.9944363074965, 4301.0, 4331.7617531035785, 5969.850753731148, 5980.55145864062, 5980.671063091571, 5982.491488223506, 5990.161377407167, 4367.2575662137515, 4401.801918913657, 4416.6908316418185, 4418.270985287391, 4447.844325448594, 4368.737875727466, 4374.212641724456, 4400.73595584266, 4454.37638730578, 4494.393256910341, 4926.802927882916, 4928.408590969107, 4945.887175385581, 4961.7519124044375, 4973.403191617029, 5796.971015144982, 5823.912641310384, 5853.122031695195, 5861.472467477947, 5862.472377486047, 1481.0, 1532.777747226646, 1533.8082739657543, 1535.7389243828577, 1543.0433131458344, 972.5866506734609, 986.8273052215834, 991.7619771254847, 1004.5027711282962, 1014.9238807089498, 4966.478519755374, 4982.60139188864, 4983.775730092155, 4989.875253118701, 5047.318611987408, 5191.766370513336, 5232.0438951992, 5232.555232805178, 5247.1866860636665, 5283.675182919503, 5017.799288028511, 5030.89938201234, 5158.8392611662075, 5188.625014371195, 5427.267318911975, 3110.315730539278, 3120.2667373167287, 3120.7035331643456, 3126.2028182813237, 3126.9530957035663, 6043.909341359883, 6093.6039157454625, 6101.359357267423, 6108.8778224436155, 6122.0, 5744.195812586241, 5946.0, 5980.850478738066, 6005.969970150121, 6068.787592434243, 6040.879182416327, 6043.884640375833, 6058.57589968698, 6062.120992456069, 6101.786437474667, 5707.600467967202, 5895.469407753323, 5929.318128415115, 5938.782549577412, 5946.732397042057, 5875.823725288248, 5880.705964701833, 5889.676067816311, 5899.528037757055, 5909.2908850938165, 6007.879842403215, 6041.879162416815, 6126.969365153146, 6136.907946380718, 6158.661273629993, 4156.854510092132, 4156.9192010076995, 4248.468941382327, 4283.22901877665, 4290.2921018031675, 5959.22530071088, 5963.135345374983, 5963.970180149072, 5978.940210597838, 6006.219191505076, 5600.551955843604, 5879.911801323069, 5886.440788125121, 5894.0, 5907.4683278505145, 5717.771289148387, 5755.769769209185, 5761.452661997104, 5767.250257466502, 5777.768889244383, 5529.806456773994, 5714.771409143754, 5717.942820571741, 5730.799422020376, 5789.784145329528, 5694.544436445157, 5817.883642327214, 5819.79630712923, 5836.358000620014, 5846.736896839738, 5694.86442584031, 5701.40135285789, 5703.233931543115, 5709.7145142741865, 5741.5119714824705], "value": 4413.865618945231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.5691398187989, 822.9300509456694, 827.3298628111227, 832.671094917508, 935.5556110847352, 15958.202089895502, 18261.086945652696, 18438.0, 18508.537286567844, 18542.629147417043, 15890.84109158908, 16328.101954392534, 16568.337266509327, 16590.0, 16594.0, 901.2159421303459, 927.8886533615976, 929.2380248196481, 929.7117893453051, 929.8233335666235, 917.9632814687404, 920.7421921861855, 922.0134456131941, 922.4419226368051, 922.6263363337825, 969.9515024248759, 970.8495183246608, 971.3637567393333, 971.7230589282055, 971.8493633486786, 962.1484985787629, 963.3978763272954, 964.0167029629738, 964.1659964131046, 965.0879918477042, 712.6401167410424, 712.975045873392, 713.2083387439937, 713.2510863593207, 713.4684659928387, 15111.924440377838, 15317.0, 15355.385784568716, 15356.155411452366, 15374.308156132889, 14342.211178385185, 14603.70792584153, 14662.926685366612, 14812.407503699838, 15420.0, 13817.861821381757, 13849.37677804491, 13876.375563099684, 14106.71786564263, 14402.855971440362, 4774.450938142123, 4968.329275547827, 4978.5270399312, 5089.9745501272255, 5098.515641014098, 55480.72259638716, 69547.65226173837, 72180.6390968047, 72571.27428725698, 88210.0, 12594.559190428292, 12964.80552791709, 13129.277889715966, 13162.14446061003, 13416.932915335556, 22801.31596052121, 22870.199543015915, 22884.885575572014, 22909.541809163722, 22922.885385572965, 7545.320921117126, 8692.391532592657, 9283.90716092844, 9618.374805637752, 9680.0, 5159.329287192641, 5278.313819203556, 5453.890922181534, 5470.781168753285, 5574.247476590608, 3882.9611703883165, 4094.28350038742, 4120.773357465308, 4125.092479654471, 4130.710850240514, 683.54885775389, 691.9550229235184, 697.9197392299849, 698.993010069903, 706.3148745716646, 796.6534557467404, 814.7800093974594, 815.5392203405057, 816.1675091406731, 829.5810615639064, 10151.238657100746, 10583.153347732163, 10848.67453976382, 10907.83638245427, 11628.360440175702, 806.7458750493576, 810.3193317613233, 857.0, 860.5912191709014, 882.4176043811182, 4979.601631869442, 5074.873128171826, 5112.974435127875, 5156.845294641167, 5273.551748101378, 112158.87841121647, 115741.0, 119364.40317798355, 124260.37869810592, 124934.37532812278, 98529.0, 103127.48436257771, 106559.0, 106562.46718766357, 106676.46661766642, 60512.697436512535, 62434.37565624285, 78728.606356969, 81545.59227203827, 91578.54210728903, 10627.840582391425, 11069.446527673745, 11164.609238676607, 11190.384528850991, 11295.435228238719, 50306.0, 51092.74453627708, 52050.47949520456, 55611.0, 55964.0, 64890.93499945201, 65780.0, 77422.0, 78378.6081069591, 79159.60420198092, 13718.24549649739, 13920.182077492147, 13933.23367214813, 14177.0, 14195.432182712575, 15471.226438677795, 15761.68476630484, 15931.442399515961, 15943.36226550925, 16152.596185095466, 15229.923850380677, 15528.922355388151, 15828.0, 16568.420105296256, 16596.50210493687, 14451.92774036123, 14869.405223790927, 15216.391344346102, 15552.84447155514, 15610.45363412275, 14725.705485890436, 14789.482368117064, 14936.701265974836, 15114.622134446727, 15320.157391343582, 11923.880761192624, 12335.198212116542, 12514.937425312815, 13029.47882084706, 13176.538821141214, 11916.033854413925, 12700.809487857505, 12811.743765124831, 12962.93518532439, 13142.474301027853], "value": 8310.681879205831, "unit": "score"}], "name": "dromaeo_css", "value": 7774.864222709934, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 113.0, 112.0, 111.0, 112.0, 109.0, 110.0, 111.0, 109.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 116.0, 183.0, 116.0, 115.0, 148.0, 119.0, 151.0, 117.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 160.0, 160.0, 160.0, 160.0, 158.0, 162.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 111.0, 75.0, 76.0, 75.0, 75.0, 109.0, 74.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 140.0, 84.0, 84.0, 83.0, 116.0, 83.0, 84.0, 118.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 50.0, 50.0, 49.0, 50.0, 51.0, 49.0, 49.0, 49.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 72.0, 75.0, 73.0, 71.0, 73.0, 72.0, 75.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 116.0, 116.0, 115.0, 126.0, 117.0, 147.0, 117.0], "value": 120.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 152.0, 159.0, 151.0, 157.0, 152.0, 152.0, 152.0, 153.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 63.0, 61.0, 63.0, 61.0, 60.0, 60.0, 60.0, 59.0], "value": 60.9, "unit": "score"}], "name": "kraken", "value": 1454.6999999999998, "alertThreshold": 2.0}]} 00:24:29 INFO - Return code: 0 00:24:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:24:29 INFO - # TBPL SUCCESS # 00:24:29 INFO - Running post-action listener: _resource_record_post_action 00:24:29 INFO - [mozharness: 2016-08-31 07:24:29.674991Z] Finished run-tests step (success) 00:24:29 INFO - Running post-run listener: _resource_record_post_run 00:24:29 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 330870784; Read time: 68; Write time: 245024 00:24:29 INFO - TinderboxPrint: CPU usage
12.9% 00:24:29 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 68 00:24:29 INFO - TinderboxPrint: I/O write bytes / time
330,870,784 / 245,024 00:24:29 INFO - TinderboxPrint: CPU idle
8,259.9 (87.0%) 00:24:29 INFO - TinderboxPrint: CPU user
1,185.0 (12.5%) 00:24:29 INFO - TinderboxPrint: Swap in / out
0 / 0 00:24:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79241216; Read time: 0; Write time: 69712 00:24:29 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 251629568; Read time: 68; Write time: 175312 00:24:30 INFO - Running post-run listener: _upload_blobber_files 00:24:30 INFO - Blob upload gear active. 00:24:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:24:30 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 00:24:30 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'] 00:24:30 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 00:24:30 INFO - (blobuploader) - INFO - Open directory for files ... 00:24:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:24:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:24:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:24:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:24:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:24:31 INFO - (blobuploader) - INFO - Done attempting. 00:24:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:24:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:24:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:24:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:24:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:24:35 INFO - (blobuploader) - INFO - Done attempting. 00:24:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:24:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:24:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:24:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:24:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:24:35 INFO - (blobuploader) - INFO - Done attempting. 00:24:35 INFO - (blobuploader) - INFO - Iteration through files over. 00:24:35 INFO - Return code: 0 00:24:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:24:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:24:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb6c2a3ca13c8a05745f17ed39cfa8dce3d6dc627ab19fb48096207580ef436cfd0294637a17f8e8187518714db37b5087b9fdbfdd2c6da3fe5a76a011c34b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e220690caf9164d5d075d6c8d22f6df36e35679ea069cfc5d8852eaa4bb1fb54ef61a789c2dd8a566fca760c3f98a078097bd7b441bd33925d37d08f1c609bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59424bb50b09c87cdd2caf3ce66b59ab91dc4145bd3b97555fb0e13996126762e48bfa0cc96646095f192310236b7f2a11952e61a2bba49610fa29377fe4512c"} 00:24:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:24:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:24:35 INFO - Contents: 00:24:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb6c2a3ca13c8a05745f17ed39cfa8dce3d6dc627ab19fb48096207580ef436cfd0294637a17f8e8187518714db37b5087b9fdbfdd2c6da3fe5a76a011c34b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e220690caf9164d5d075d6c8d22f6df36e35679ea069cfc5d8852eaa4bb1fb54ef61a789c2dd8a566fca760c3f98a078097bd7b441bd33925d37d08f1c609bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59424bb50b09c87cdd2caf3ce66b59ab91dc4145bd3b97555fb0e13996126762e48bfa0cc96646095f192310236b7f2a11952e61a2bba49610fa29377fe4512c"} 00:24:35 INFO - Running post-run listener: copy_logs_to_upload_dir 00:24:35 INFO - Copying logs to upload dir... 00:24:35 INFO - mkdir: /builds/slave/test/build/upload/logs 00:24:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.008057 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-31 00:24:35.421866) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 00:24:35.425458) ========= 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=826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694 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/fb6c2a3ca13c8a05745f17ed39cfa8dce3d6dc627ab19fb48096207580ef436cfd0294637a17f8e8187518714db37b5087b9fdbfdd2c6da3fe5a76a011c34b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e220690caf9164d5d075d6c8d22f6df36e35679ea069cfc5d8852eaa4bb1fb54ef61a789c2dd8a566fca760c3f98a078097bd7b441bd33925d37d08f1c609bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59424bb50b09c87cdd2caf3ce66b59ab91dc4145bd3b97555fb0e13996126762e48bfa0cc96646095f192310236b7f2a11952e61a2bba49610fa29377fe4512c"} build_url:https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/ZU86gQDnSwSn8C6V_FLs1Q/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/fb6c2a3ca13c8a05745f17ed39cfa8dce3d6dc627ab19fb48096207580ef436cfd0294637a17f8e8187518714db37b5087b9fdbfdd2c6da3fe5a76a011c34b17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e220690caf9164d5d075d6c8d22f6df36e35679ea069cfc5d8852eaa4bb1fb54ef61a789c2dd8a566fca760c3f98a078097bd7b441bd33925d37d08f1c609bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59424bb50b09c87cdd2caf3ce66b59ab91dc4145bd3b97555fb0e13996126762e48bfa0cc96646095f192310236b7f2a11952e61a2bba49610fa29377fe4512c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 00:24:35.450865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 00:24:35.451225) ========= 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=826874ccc97bd7bf541b4875000001d5-1472625565.219530-295102694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 00:24:35.536978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 00:24:35.537317) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 00:24:35.537736) ========= ========= Total master_lag: 0.16 =========