builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1471901249.21 results: success (0) buildid: 20160822113013 builduid: 27b55041237a49d19719bf80e876a6a5 revision: 7b44f5b3b0bb503b79166ac861b1f3f9c1f8abb9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.214813) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.215311) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.215614) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.230841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.231247) ========= 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=232498196343f89f466a4a9a000001c8-1471901249.97257-45535204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 14:27:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-08-22 14:27:30 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085401 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.331356) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.331764) ========= 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=232498196343f89f466a4a9a000001c8-1471901249.97257-45535204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013730 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:29.382484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 14:27:29.382954) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7b44f5b3b0bb503b79166ac861b1f3f9c1f8abb9 --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 7b44f5b3b0bb503b79166ac861b1f3f9c1f8abb9 --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=232498196343f89f466a4a9a000001c8-1471901249.97257-45535204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 14:27:30,415 truncating revision to first 12 chars 2016-08-22 14:27:30,415 Setting DEBUG logging. 2016-08-22 14:27:30,416 attempt 1/10 2016-08-22 14:27:30,416 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7b44f5b3b0bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 14:27:31,335 unpacking tar archive at: mozilla-aurora-7b44f5b3b0bb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267323 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 14:27:30.664969) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:30.665321) ========= script_repo_revision: 7b44f5b3b0bb503b79166ac861b1f3f9c1f8abb9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:30.665844) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:30.666285) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 14:27:30.675885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-22 14:27:30.676207) ========= /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=232498196343f89f466a4a9a000001c8-1471901249.97257-45535204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:27:31 INFO - MultiFileLogger online at 20160822 14:27:31 in /builds/slave/test 14:27:31 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 14:27:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:27:31 INFO - {'append_to_log': False, 14:27:31 INFO - 'base_work_dir': '/builds/slave/test', 14:27:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:27:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:27:31 INFO - 'branch': 'Mozilla-Aurora', 14:27:31 INFO - 'buildbot_json_path': 'buildprops.json', 14:27:31 INFO - 'config_files': ('talos/linux_config.py',), 14:27:31 INFO - 'default_actions': ('clobber', 14:27:31 INFO - 'read-buildbot-config', 14:27:31 INFO - 'download-and-extract', 14:27:31 INFO - 'populate-webroot', 14:27:31 INFO - 'create-virtualenv', 14:27:31 INFO - 'install', 14:27:31 INFO - 'run-tests'), 14:27:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:27:31 INFO - 'download_minidump_stackwalk': True, 14:27:31 INFO - 'download_symbols': 'ondemand', 14:27:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:27:31 INFO - 'tooltool.py': '/tools/tooltool.py', 14:27:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:27:31 INFO - '/tools/misc-python/virtualenv.py')}, 14:27:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:27:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:27:31 INFO - 'installer_path': 'installer.exe', 14:27:31 INFO - 'log_level': 'info', 14:27:31 INFO - 'log_name': 'talos', 14:27:31 INFO - 'log_to_console': True, 14:27:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:27:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:27:31 INFO - 'opt_config_files': (), 14:27:31 INFO - 'pip_index': False, 14:27:31 INFO - 'sps_profile': False, 14:27:31 INFO - 'sps_profile_interval': 0, 14:27:31 INFO - 'suite': 'dromaeojs', 14:27:31 INFO - 'system_bits': '32', 14:27:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:27:31 INFO - 'title': 'talos-linux64-ix-025', 14:27:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:27:31 INFO - 'use_talos_json': True, 14:27:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:27:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:27:31 INFO - 'work_dir': 'build'} 14:27:31 INFO - [mozharness: 2016-08-22 21:27:31.797928Z] Running clobber step. 14:27:31 INFO - Running pre-action listener: _resource_record_pre_action 14:27:31 INFO - Running main action method: clobber 14:27:31 INFO - rmtree: /builds/slave/test/build 14:27:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:27:32 INFO - Running post-action listener: _resource_record_post_action 14:27:32 INFO - [mozharness: 2016-08-22 21:27:32.258769Z] Finished clobber step (success) 14:27:32 INFO - [mozharness: 2016-08-22 21:27:32.258869Z] Running read-buildbot-config step. 14:27:32 INFO - Running pre-action listener: _resource_record_pre_action 14:27:32 INFO - Running main action method: read_buildbot_config 14:27:32 INFO - Using buildbot properties: 14:27:32 INFO - { 14:27:32 INFO - "project": "", 14:27:32 INFO - "product": "firefox", 14:27:32 INFO - "script_repo_revision": "production", 14:27:32 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:27:32 INFO - "repository": "", 14:27:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:27:32 INFO - "buildid": "20160822113013", 14:27:32 INFO - "pgo_build": "True", 14:27:32 INFO - "basedir": "/builds/slave/test", 14:27:32 INFO - "buildnumber": 483, 14:27:32 INFO - "slavename": "talos-linux64-ix-025", 14:27:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:27:32 INFO - "platform": "ubuntu64_hw", 14:27:32 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:27:32 INFO - "branch": "mozilla-aurora", 14:27:32 INFO - "repo_path": "releases/mozilla-aurora", 14:27:32 INFO - "revision": "7b44f5b3b0bb503b79166ac861b1f3f9c1f8abb9", 14:27:32 INFO - "stage_platform": "linux64-pgo", 14:27:32 INFO - "builduid": "27b55041237a49d19719bf80e876a6a5", 14:27:32 INFO - "slavebuilddir": "test" 14:27:32 INFO - } 14:27:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:27:32 INFO - Running post-action listener: _resource_record_post_action 14:27:32 INFO - [mozharness: 2016-08-22 21:27:32.261310Z] Finished read-buildbot-config step (success) 14:27:32 INFO - [mozharness: 2016-08-22 21:27:32.261399Z] Running download-and-extract step. 14:27:32 INFO - Running pre-action listener: _resource_record_pre_action 14:27:32 INFO - Running main action method: download_and_extract 14:27:32 INFO - mkdir: /builds/slave/test/build/tests 14:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:27:32 INFO - https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:27:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:27:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:27:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:27:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:27:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:27:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:27:32 INFO - retry: Failed, sleeping 30 seconds before retrying 14:28:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:28:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:28:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:28:02 INFO - retry: Failed, sleeping 60 seconds before retrying 14:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:29:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:29:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:29:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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! 14:29:02 INFO - Caught exception: HTTP Error 404: Not Found 14:29:02 INFO - Caught exception: HTTP Error 404: Not Found 14:29:02 INFO - Caught exception: HTTP Error 404: Not Found 14:29:02 INFO - trying https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:29:02 INFO - Downloading https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:29:02 INFO - Downloaded 1361 bytes. 14:29:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:29:02 INFO - Using the following test package requirements: 14:29:02 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:29:02 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:29:02 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:29:02 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'jsshell-linux-x86_64.zip'], 14:29:02 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:29:02 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:29:02 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:29:02 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:29:02 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:29:02 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:29:02 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:02 INFO - https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:29:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:29:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:29:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:29:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:29:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:29:02 INFO - retry: Failed, sleeping 30 seconds before retrying 14:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:29:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:29:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:29:32 INFO - retry: Failed, sleeping 60 seconds before retrying 14:30:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:30:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:30:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:30:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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! 14:30:33 INFO - Caught exception: HTTP Error 404: Not Found 14:30:33 INFO - Caught exception: HTTP Error 404: Not Found 14:30:33 INFO - Caught exception: HTTP Error 404: Not Found 14:30:33 INFO - trying https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:30:33 INFO - Downloading https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:30:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:30:33 INFO - Downloaded 21768958 bytes. 14:30:33 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'] 14:30:33 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 14:30:35 INFO - Return code: 0 14:30:35 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 14:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:35 INFO - https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:30:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:30:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:30:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:30:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:30:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:30:35 INFO - retry: Failed, sleeping 30 seconds before retrying 14:31:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:31:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:31:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:31:05 INFO - retry: Failed, sleeping 60 seconds before retrying 14:32:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:32:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:32:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:32:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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! 14:32:05 INFO - Caught exception: HTTP Error 404: Not Found 14:32:05 INFO - Caught exception: HTTP Error 404: Not Found 14:32:05 INFO - Caught exception: HTTP Error 404: Not Found 14:32:05 INFO - trying https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:32:05 INFO - Downloading https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:32:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:32:05 INFO - Downloaded 21768958 bytes. 14:32:05 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'] 14:32:05 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 14:32:07 INFO - Return code: 0 14:32:07 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')]} 14:32:07 INFO - https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:32:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:32:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:32:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:32:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:32:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:32:07 INFO - retry: Failed, sleeping 30 seconds before retrying 14:32:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:32:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:32:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:32:37 INFO - retry: Failed, sleeping 60 seconds before retrying 14:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:33:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:33:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:33:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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! 14:33:37 INFO - Caught exception: HTTP Error 404: Not Found 14:33:37 INFO - Caught exception: HTTP Error 404: Not Found 14:33:37 INFO - Caught exception: HTTP Error 404: Not Found 14:33:37 INFO - trying https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:33:37 INFO - Downloading https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:33:38 INFO - Downloaded 14413723 bytes. 14:33:38 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'] 14:33:38 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 14:33:38 INFO - Return code: 0 14:33: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')]} 14:33:38 INFO - https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:33:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:33:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:33:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:33:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:33:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:33:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:33:38 INFO - retry: Failed, sleeping 30 seconds before retrying 14:34:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:34:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:34:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:34:08 INFO - retry: Failed, sleeping 60 seconds before retrying 14:35:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:35:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:35:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:35:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U2T52vHUQmiw4ELmJQDxdg/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! 14:35:08 INFO - Caught exception: HTTP Error 404: Not Found 14:35:08 INFO - Caught exception: HTTP Error 404: Not Found 14:35:08 INFO - Caught exception: HTTP Error 404: Not Found 14:35:08 INFO - trying https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:35:08 INFO - Downloading https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:35:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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 14:35:09 INFO - Downloaded 63748461 bytes. 14:35:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:35:09 INFO - mkdir: /builds/slave/test/properties 14:35:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:35:09 INFO - Writing to file /builds/slave/test/properties/build_url 14:35:09 INFO - Contents: 14:35:09 INFO - build_url:https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:35:10 INFO - Running post-action listener: _resource_record_post_action 14:35:10 INFO - Running post-action listener: set_extra_try_arguments 14:35:10 INFO - [mozharness: 2016-08-22 21:35:10.565580Z] Finished download-and-extract step (success) 14:35:10 INFO - [mozharness: 2016-08-22 21:35:10.565811Z] Running populate-webroot step. 14:35:10 INFO - Running pre-action listener: _resource_record_pre_action 14:35:10 INFO - Running main action method: populate_webroot 14:35:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:35:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:35:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:35:10 INFO - '--tppagecycles', 14:35:10 INFO - '7'], 14:35:10 INFO - 'tests': ['tsvgm']}}, 14:35:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:35:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:35:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:35:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:35:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:35:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:35:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['damp', 'tps']}, 14:35:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'tests': ['damp', 'tps']}, 14:35:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['dromaeo_dom']}, 14:35:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:35:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['basic_compositor_video']}, 14:35:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:35:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['a11yr', 14:35:10 INFO - 'ts_paint', 14:35:10 INFO - 'tpaint', 14:35:10 INFO - 'sessionrestore', 14:35:10 INFO - 'sessionrestore_no_auto_restore', 14:35:10 INFO - 'tabpaint']}, 14:35:10 INFO - 'other-e10s': {'tests': ['a11yr', 14:35:10 INFO - 'ts_paint', 14:35:10 INFO - 'tpaint', 14:35:10 INFO - 'sessionrestore', 14:35:10 INFO - 'sessionrestore_no_auto_restore', 14:35:10 INFO - 'tabpaint']}, 14:35:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:35:10 INFO - 'ts_paint', 14:35:10 INFO - 'tpaint', 14:35:10 INFO - 'sessionrestore', 14:35:10 INFO - 'sessionrestore_no_auto_restore', 14:35:10 INFO - 'tabpaint']}, 14:35:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:35:10 INFO - 'ts_paint', 14:35:10 INFO - 'tpaint', 14:35:10 INFO - 'sessionrestore', 14:35:10 INFO - 'sessionrestore_no_auto_restore', 14:35:10 INFO - 'tabpaint']}, 14:35:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['a11yr', 14:35:10 INFO - 'ts_paint', 14:35:10 INFO - 'tpaint', 14:35:10 INFO - 'sessionrestore', 14:35:10 INFO - 'sessionrestore_no_auto_restore', 14:35:10 INFO - 'tabpaint']}, 14:35:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['a11yr', 14:35:10 INFO - 'ts_paint', 14:35:10 INFO - 'tpaint', 14:35:10 INFO - 'sessionrestore', 14:35:10 INFO - 'sessionrestore_no_auto_restore', 14:35:10 INFO - 'tabpaint']}, 14:35:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['tsvgx', 14:35:10 INFO - 'tsvgr_opacity', 14:35:10 INFO - 'tart', 14:35:10 INFO - 'tscrollx', 14:35:10 INFO - 'cart']}, 14:35:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:35:10 INFO - 'tsvgr_opacity', 14:35:10 INFO - 'tart', 14:35:10 INFO - 'tscrollx', 14:35:10 INFO - 'cart']}, 14:35:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'talos_options': ['--disable-e10s'], 14:35:10 INFO - 'tests': ['tp5o']}, 14:35:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'tests': ['tp5o']}, 14:35:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'talos_options': ['--disable-e10s', 14:35:10 INFO - '--xperf_path', 14:35:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:35:10 INFO - 'tests': ['tp5n']}, 14:35:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:35:10 INFO - 'talos_options': ['--xperf_path', 14:35:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:35:10 INFO - 'tests': ['tp5n']}}, 14:35:10 INFO - 'talos.zip': {'path': '', 14:35:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:35:10 INFO - Running post-action listener: _resource_record_post_action 14:35:10 INFO - [mozharness: 2016-08-22 21:35:10.580927Z] Finished populate-webroot step (success) 14:35:10 INFO - [mozharness: 2016-08-22 21:35:10.581015Z] Running create-virtualenv step. 14:35:10 INFO - Running pre-action listener: _resource_record_pre_action 14:35:10 INFO - Running main action method: create_virtualenv 14:35:10 INFO - Creating virtualenv /builds/slave/test/build/venv 14:35:10 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 14:35:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:35:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:35:10 INFO - Using real prefix '/usr' 14:35:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:35:11 INFO - Installing distribute.............................................................................................................................................................................................done. 14:35:13 INFO - Installing pip.................done. 14:35:13 INFO - Return code: 0 14:35:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:35:13 INFO - Using env: {'DISPLAY': ':0', 14:35:13 INFO - 'HOME': '/home/cltbld', 14:35:13 INFO - 'LANG': 'en_US.UTF-8', 14:35:13 INFO - 'LANGUAGE': 'en_US:en', 14:35:13 INFO - 'LOGNAME': 'cltbld', 14:35:13 INFO - 'MAIL': '/var/mail/cltbld', 14:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:13 INFO - 'MOZ_NO_REMOTE': '1', 14:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:13 INFO - 'NO_EM_RESTART': '1', 14:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:13 INFO - 'PWD': '/builds/slave/test', 14:35:13 INFO - 'SHELL': '/bin/bash', 14:35:13 INFO - 'SHLVL': '1', 14:35:13 INFO - 'TERM': 'linux', 14:35:13 INFO - 'TMOUT': '86400', 14:35:13 INFO - 'USER': 'cltbld', 14:35:13 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:13 INFO - '_': '/tools/buildbot/bin/python'} 14:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:13 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 14:35:13 INFO - Cleaning up... 14:35:13 INFO - Return code: 0 14:35:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:35:13 INFO - Using env: {'DISPLAY': ':0', 14:35:13 INFO - 'HOME': '/home/cltbld', 14:35:13 INFO - 'LANG': 'en_US.UTF-8', 14:35:13 INFO - 'LANGUAGE': 'en_US:en', 14:35:13 INFO - 'LOGNAME': 'cltbld', 14:35:13 INFO - 'MAIL': '/var/mail/cltbld', 14:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:13 INFO - 'MOZ_NO_REMOTE': '1', 14:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:13 INFO - 'NO_EM_RESTART': '1', 14:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:13 INFO - 'PWD': '/builds/slave/test', 14:35:13 INFO - 'SHELL': '/bin/bash', 14:35:13 INFO - 'SHLVL': '1', 14:35:13 INFO - 'TERM': 'linux', 14:35:13 INFO - 'TMOUT': '86400', 14:35:13 INFO - 'USER': 'cltbld', 14:35:13 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:13 INFO - '_': '/tools/buildbot/bin/python'} 14:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:13 INFO - Downloading/unpacking psutil>=3.1.1 14:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:35:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:35:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:35:15 INFO - Installing collected packages: psutil 14:35:15 INFO - Running setup.py install for psutil 14:35:15 INFO - building 'psutil._psutil_linux' extension 14:35:15 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 14:35:15 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 14:35:15 INFO - building 'psutil._psutil_posix' extension 14:35:15 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 14:35:15 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 14:35:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:35:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:35:16 INFO - Successfully installed psutil 14:35:16 INFO - Cleaning up... 14:35:16 INFO - Return code: 0 14:35:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:35:16 INFO - Using env: {'DISPLAY': ':0', 14:35:16 INFO - 'HOME': '/home/cltbld', 14:35:16 INFO - 'LANG': 'en_US.UTF-8', 14:35:16 INFO - 'LANGUAGE': 'en_US:en', 14:35:16 INFO - 'LOGNAME': 'cltbld', 14:35:16 INFO - 'MAIL': '/var/mail/cltbld', 14:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:16 INFO - 'MOZ_NO_REMOTE': '1', 14:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:16 INFO - 'NO_EM_RESTART': '1', 14:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:16 INFO - 'PWD': '/builds/slave/test', 14:35:16 INFO - 'SHELL': '/bin/bash', 14:35:16 INFO - 'SHLVL': '1', 14:35:16 INFO - 'TERM': 'linux', 14:35:16 INFO - 'TMOUT': '86400', 14:35:16 INFO - 'USER': 'cltbld', 14:35:16 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:16 INFO - '_': '/tools/buildbot/bin/python'} 14:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:35:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:35:18 INFO - Installing collected packages: mozsystemmonitor 14:35:18 INFO - Running setup.py install for mozsystemmonitor 14:35:18 INFO - Successfully installed mozsystemmonitor 14:35:18 INFO - Cleaning up... 14:35:18 INFO - Return code: 0 14:35:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:35:18 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')]} 14:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:18 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')]} 14:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:18 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 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:18 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 14:35:18 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 14:35:18 INFO - Using env: {'DISPLAY': ':0', 14:35:18 INFO - 'HOME': '/home/cltbld', 14:35:18 INFO - 'LANG': 'en_US.UTF-8', 14:35:18 INFO - 'LANGUAGE': 'en_US:en', 14:35:18 INFO - 'LOGNAME': 'cltbld', 14:35:18 INFO - 'MAIL': '/var/mail/cltbld', 14:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:18 INFO - 'MOZ_NO_REMOTE': '1', 14:35:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:18 INFO - 'NO_EM_RESTART': '1', 14:35:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:18 INFO - 'PWD': '/builds/slave/test', 14:35:18 INFO - 'SHELL': '/bin/bash', 14:35:18 INFO - 'SHLVL': '1', 14:35:18 INFO - 'TERM': 'linux', 14:35:18 INFO - 'TMOUT': '86400', 14:35:18 INFO - 'USER': 'cltbld', 14:35:18 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:18 INFO - '_': '/tools/buildbot/bin/python'} 14:35:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:18 INFO - Downloading/unpacking jsonschema==2.5.1 14:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:35:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:35:20 INFO - Downloading functools32-3.2.3-2.tar.gz 14:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:35:20 INFO - warning: no files found matching '*.txt' 14:35:20 INFO - no previously-included directories found matching 'build' 14:35:20 INFO - no previously-included directories found matching 'dist' 14:35:20 INFO - no previously-included directories found matching '.git*' 14:35:20 INFO - Installing collected packages: jsonschema, functools32 14:35:20 INFO - Running setup.py install for functools32 14:35:20 INFO - warning: no files found matching '*.txt' 14:35:20 INFO - no previously-included directories found matching 'build' 14:35:20 INFO - no previously-included directories found matching 'dist' 14:35:20 INFO - no previously-included directories found matching '.git*' 14:35:20 INFO - Successfully installed jsonschema functools32 14:35:20 INFO - Cleaning up... 14:35:20 INFO - Return code: 0 14:35:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:35:20 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')]} 14:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35: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')]} 14:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35: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', '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 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:35: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 functools32==3.2.3-2 14:35:21 INFO - Using env: {'DISPLAY': ':0', 14:35:21 INFO - 'HOME': '/home/cltbld', 14:35:21 INFO - 'LANG': 'en_US.UTF-8', 14:35:21 INFO - 'LANGUAGE': 'en_US:en', 14:35:21 INFO - 'LOGNAME': 'cltbld', 14:35:21 INFO - 'MAIL': '/var/mail/cltbld', 14:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:21 INFO - 'MOZ_NO_REMOTE': '1', 14:35:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:21 INFO - 'NO_EM_RESTART': '1', 14:35:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:21 INFO - 'PWD': '/builds/slave/test', 14:35:21 INFO - 'SHELL': '/bin/bash', 14:35:21 INFO - 'SHLVL': '1', 14:35:21 INFO - 'TERM': 'linux', 14:35:21 INFO - 'TMOUT': '86400', 14:35:21 INFO - 'USER': 'cltbld', 14:35:21 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:21 INFO - '_': '/tools/buildbot/bin/python'} 14:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:35:21 INFO - Cleaning up... 14:35:21 INFO - Return code: 0 14:35:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:35: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')]} 14:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35: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')]} 14:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35: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', '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 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:35: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 blobuploader==1.2.4 14:35:21 INFO - Using env: {'DISPLAY': ':0', 14:35:21 INFO - 'HOME': '/home/cltbld', 14:35:21 INFO - 'LANG': 'en_US.UTF-8', 14:35:21 INFO - 'LANGUAGE': 'en_US:en', 14:35:21 INFO - 'LOGNAME': 'cltbld', 14:35:21 INFO - 'MAIL': '/var/mail/cltbld', 14:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:21 INFO - 'MOZ_NO_REMOTE': '1', 14:35:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:21 INFO - 'NO_EM_RESTART': '1', 14:35:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:21 INFO - 'PWD': '/builds/slave/test', 14:35:21 INFO - 'SHELL': '/bin/bash', 14:35:21 INFO - 'SHLVL': '1', 14:35:21 INFO - 'TERM': 'linux', 14:35:21 INFO - 'TMOUT': '86400', 14:35:21 INFO - 'USER': 'cltbld', 14:35:21 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:21 INFO - '_': '/tools/buildbot/bin/python'} 14:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:21 INFO - Downloading/unpacking blobuploader==1.2.4 14:35: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 14:35: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 14:35: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 14:35: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 14:35: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 14:35: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 14:35:23 INFO - Downloading blobuploader-1.2.4.tar.gz 14:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:35:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:35:23 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 14:35:23 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 14:35:23 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 14:35:23 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 14:35:23 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 14:35:23 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 14:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:35:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:35:23 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 14:35:23 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 14:35:23 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 14:35:23 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 14:35:23 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 14:35:23 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 14:35:24 INFO - Downloading docopt-0.6.1.tar.gz 14:35:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:35:24 INFO - Installing collected packages: blobuploader, requests, docopt 14:35:24 INFO - Running setup.py install for blobuploader 14:35:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:35:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:35:24 INFO - Running setup.py install for requests 14:35:24 INFO - Running setup.py install for docopt 14:35:24 INFO - Successfully installed blobuploader requests docopt 14:35:24 INFO - Cleaning up... 14:35:24 INFO - Return code: 0 14:35:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:35: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')]} 14:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35: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')]} 14:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:24 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 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:24 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 14:35:24 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 14:35:24 INFO - Using env: {'DISPLAY': ':0', 14:35:24 INFO - 'HOME': '/home/cltbld', 14:35:24 INFO - 'LANG': 'en_US.UTF-8', 14:35:24 INFO - 'LANGUAGE': 'en_US:en', 14:35:24 INFO - 'LOGNAME': 'cltbld', 14:35:24 INFO - 'MAIL': '/var/mail/cltbld', 14:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:24 INFO - 'MOZ_NO_REMOTE': '1', 14:35:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:24 INFO - 'NO_EM_RESTART': '1', 14:35:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:24 INFO - 'PWD': '/builds/slave/test', 14:35:24 INFO - 'SHELL': '/bin/bash', 14:35:24 INFO - 'SHLVL': '1', 14:35:24 INFO - 'TERM': 'linux', 14:35:24 INFO - 'TMOUT': '86400', 14:35:24 INFO - 'USER': 'cltbld', 14:35:24 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:24 INFO - '_': '/tools/buildbot/bin/python'} 14:35:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:35:25 INFO - Running setup.py (path:/tmp/pip-ypRzBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:35:25 INFO - Running setup.py (path:/tmp/pip-FqmwG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:35:25 INFO - Running setup.py (path:/tmp/pip-Un7WBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:35:25 INFO - Running setup.py (path:/tmp/pip-VR61MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:35:25 INFO - Running setup.py (path:/tmp/pip-x5xoT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:35:25 INFO - Running setup.py (path:/tmp/pip-_L0sRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:35:25 INFO - Running setup.py (path:/tmp/pip-XFe1ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:35:25 INFO - Running setup.py (path:/tmp/pip-GMmGVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:35:25 INFO - Running setup.py (path:/tmp/pip-lDZdLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:35:25 INFO - Running setup.py (path:/tmp/pip-GICIYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:35:25 INFO - Running setup.py (path:/tmp/pip-67t7Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:35:25 INFO - Running setup.py (path:/tmp/pip-2gBV_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:35:26 INFO - Running setup.py (path:/tmp/pip-cHoXJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:35:26 INFO - Running setup.py (path:/tmp/pip-QLyb3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:35:26 INFO - Running setup.py (path:/tmp/pip-iaNbBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:35:26 INFO - Running setup.py (path:/tmp/pip-vEHfk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:35:26 INFO - Running setup.py (path:/tmp/pip-TGRrfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:35:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:35:26 INFO - Running setup.py install for manifestparser 14:35:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:35:26 INFO - Running setup.py install for mozcrash 14:35:26 INFO - Running setup.py install for mozdebug 14:35:26 INFO - Running setup.py install for mozdevice 14:35:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:35:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:35:26 INFO - Running setup.py install for mozfile 14:35:26 INFO - Running setup.py install for mozhttpd 14:35:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Running setup.py install for mozinfo 14:35:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Running setup.py install for mozInstall 14:35:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Running setup.py install for mozleak 14:35:27 INFO - Running setup.py install for mozlog 14:35:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Running setup.py install for moznetwork 14:35:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Running setup.py install for mozprocess 14:35:27 INFO - Running setup.py install for mozprofile 14:35:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Running setup.py install for mozrunner 14:35:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:35:27 INFO - Running setup.py install for mozscreenshot 14:35:27 INFO - Running setup.py install for moztest 14:35:28 INFO - Running setup.py install for mozversion 14:35:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:35:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:35:28 INFO - Cleaning up... 14:35:28 INFO - Return code: 0 14:35:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:28 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 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:28 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 14:35:28 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 14:35:28 INFO - Using env: {'DISPLAY': ':0', 14:35:28 INFO - 'HOME': '/home/cltbld', 14:35:28 INFO - 'LANG': 'en_US.UTF-8', 14:35:28 INFO - 'LANGUAGE': 'en_US:en', 14:35:28 INFO - 'LOGNAME': 'cltbld', 14:35:28 INFO - 'MAIL': '/var/mail/cltbld', 14:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:28 INFO - 'MOZ_NO_REMOTE': '1', 14:35:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:28 INFO - 'NO_EM_RESTART': '1', 14:35:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:28 INFO - 'PWD': '/builds/slave/test', 14:35:28 INFO - 'SHELL': '/bin/bash', 14:35:28 INFO - 'SHLVL': '1', 14:35:28 INFO - 'TERM': 'linux', 14:35:28 INFO - 'TMOUT': '86400', 14:35:28 INFO - 'USER': 'cltbld', 14:35:28 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:28 INFO - '_': '/tools/buildbot/bin/python'} 14:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:35:28 INFO - Running setup.py (path:/tmp/pip-ITb8V1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:35:28 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)) 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:35:28 INFO - Running setup.py (path:/tmp/pip-oRmNvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:35:28 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)) 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:35:28 INFO - Running setup.py (path:/tmp/pip-hdQBMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:35:28 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)) 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:35:28 INFO - Running setup.py (path:/tmp/pip-pSk7L1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:35:28 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)) 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:35:28 INFO - Running setup.py (path:/tmp/pip-ksOHa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:35:28 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)) 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:35:28 INFO - Running setup.py (path:/tmp/pip-ZUSwWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:35:28 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)) 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:35:28 INFO - Running setup.py (path:/tmp/pip-cFcBsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:35:28 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)) 14:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:35:28 INFO - Running setup.py (path:/tmp/pip-14NYyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:35:29 INFO - Running setup.py (path:/tmp/pip-qhskFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:35:29 INFO - Running setup.py (path:/tmp/pip-fJBUpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:35:29 INFO - Running setup.py (path:/tmp/pip-2_Oowy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:35:29 INFO - Running setup.py (path:/tmp/pip-ebUg01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:35:29 INFO - Running setup.py (path:/tmp/pip-pml90_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:35:29 INFO - Running setup.py (path:/tmp/pip-VhHq0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:35:29 INFO - Running setup.py (path:/tmp/pip-A_oFoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:35:29 INFO - Running setup.py (path:/tmp/pip-wIcJu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:35:29 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)) 14:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:35:29 INFO - Running setup.py (path:/tmp/pip-zQ8iJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:35:29 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)) 14:35:29 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)) 14:35:29 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)) 14:35:29 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)) 14:35:29 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)) 14:35:29 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)) 14:35:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:35: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 14:35: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 14:35: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 14:35: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 14:35: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 14:35: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 14:35:31 INFO - Downloading blessings-1.6.tar.gz 14:35:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:35:31 INFO - Installing collected packages: blessings 14:35:31 INFO - Running setup.py install for blessings 14:35:31 INFO - Successfully installed blessings 14:35:31 INFO - Cleaning up... 14:35:31 INFO - Return code: 0 14:35:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:35:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:35:32 INFO - Reading from file tmpfile_stdout 14:35:32 INFO - Current package versions: 14:35:32 INFO - argparse == 1.2.1 14:35:32 INFO - blessings == 1.6 14:35:32 INFO - blobuploader == 1.2.4 14:35:32 INFO - docopt == 0.6.1 14:35:32 INFO - functools32 == 3.2.3-2 14:35:32 INFO - jsonschema == 2.5.1 14:35:32 INFO - manifestparser == 1.1 14:35:32 INFO - mozInstall == 1.12 14:35:32 INFO - mozcrash == 0.17 14:35:32 INFO - mozdebug == 0.1 14:35:32 INFO - mozdevice == 0.48 14:35:32 INFO - mozfile == 1.2 14:35:32 INFO - mozhttpd == 0.7 14:35:32 INFO - mozinfo == 0.9 14:35:32 INFO - mozleak == 0.1 14:35:32 INFO - mozlog == 3.3 14:35:32 INFO - moznetwork == 0.27 14:35:32 INFO - mozprocess == 0.23 14:35:32 INFO - mozprofile == 0.28 14:35:32 INFO - mozrunner == 6.12 14:35:32 INFO - mozscreenshot == 0.1 14:35:32 INFO - mozsystemmonitor == 0.3 14:35:32 INFO - moztest == 0.7 14:35:32 INFO - mozversion == 1.4 14:35:32 INFO - psutil == 3.1.1 14:35:32 INFO - requests == 1.2.3 14:35:32 INFO - wsgiref == 0.1.2 14:35:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:35: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')]} 14:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35: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')]} 14:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:32 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 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:32 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 14:35:32 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 14:35:32 INFO - Using env: {'DISPLAY': ':0', 14:35:32 INFO - 'HOME': '/home/cltbld', 14:35:32 INFO - 'LANG': 'en_US.UTF-8', 14:35:32 INFO - 'LANGUAGE': 'en_US:en', 14:35:32 INFO - 'LOGNAME': 'cltbld', 14:35:32 INFO - 'MAIL': '/var/mail/cltbld', 14:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:32 INFO - 'MOZ_NO_REMOTE': '1', 14:35:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:32 INFO - 'NO_EM_RESTART': '1', 14:35:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:32 INFO - 'PWD': '/builds/slave/test', 14:35:32 INFO - 'SHELL': '/bin/bash', 14:35:32 INFO - 'SHLVL': '1', 14:35:32 INFO - 'TERM': 'linux', 14:35:32 INFO - 'TMOUT': '86400', 14:35:32 INFO - 'USER': 'cltbld', 14:35:32 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:32 INFO - '_': '/tools/buildbot/bin/python'} 14:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 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)) 14:35:32 INFO - Cleaning up... 14:35:32 INFO - Return code: 0 14:35:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:35: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')]} 14:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:35:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35: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')]} 14:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:35:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:35:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, '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': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', '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 14:35:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:35:32 INFO - Using env: {'DISPLAY': ':0', 14:35:32 INFO - 'HOME': '/home/cltbld', 14:35:32 INFO - 'LANG': 'en_US.UTF-8', 14:35:32 INFO - 'LANGUAGE': 'en_US:en', 14:35:32 INFO - 'LOGNAME': 'cltbld', 14:35:32 INFO - 'MAIL': '/var/mail/cltbld', 14:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:32 INFO - 'MOZ_NO_REMOTE': '1', 14:35:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:32 INFO - 'NO_EM_RESTART': '1', 14:35:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:32 INFO - 'PWD': '/builds/slave/test', 14:35:32 INFO - 'SHELL': '/bin/bash', 14:35:32 INFO - 'SHLVL': '1', 14:35:32 INFO - 'TERM': 'linux', 14:35:32 INFO - 'TMOUT': '86400', 14:35:32 INFO - 'USER': 'cltbld', 14:35:32 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:32 INFO - '_': '/tools/buildbot/bin/python'} 14:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:35:32 INFO - Cleaning up... 14:35:32 INFO - Return code: 0 14:35:32 INFO - Running post-action listener: _resource_record_post_action 14:35:32 INFO - Running post-action listener: _start_resource_monitoring 14:35:32 INFO - Starting resource monitoring. 14:35:32 INFO - [mozharness: 2016-08-22 21:35:32.485178Z] Finished create-virtualenv step (success) 14:35:32 INFO - [mozharness: 2016-08-22 21:35:32.485605Z] Running install step. 14:35:32 INFO - Running pre-action listener: _resource_record_pre_action 14:35:32 INFO - Running main action method: install 14:35:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:35:32 INFO - Reading from file tmpfile_stdout 14:35:32 INFO - Detecting whether we're running mozinstall >=1.0... 14:35:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:35:32 INFO - Reading from file tmpfile_stdout 14:35:32 INFO - Output received: 14:35:32 INFO - Usage: mozinstall [options] installer 14:35:32 INFO - Options: 14:35:32 INFO - -h, --help show this help message and exit 14:35:32 INFO - -d DEST, --destination=DEST 14:35:32 INFO - Directory to install application into. [default: 14:35:32 INFO - "/builds/slave/test"] 14:35:32 INFO - --app=APP Application being installed. [default: firefox] 14:35:32 INFO - mkdir: /builds/slave/test/build/application 14:35:32 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'] 14:35:32 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 14:35:49 INFO - Reading from file tmpfile_stdout 14:35:49 INFO - Output received: 14:35:49 INFO - /builds/slave/test/build/application/firefox/firefox 14:35:49 INFO - Running post-action listener: _resource_record_post_action 14:35:49 INFO - [mozharness: 2016-08-22 21:35:49.138466Z] Finished install step (success) 14:35:49 INFO - [mozharness: 2016-08-22 21:35:49.138806Z] Running run-tests step. 14:35:49 INFO - Running pre-action listener: _resource_record_pre_action 14:35:49 INFO - Running main action method: run_tests 14:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:35:49 INFO - Python 2.7.3 14:35:49 INFO - Return code: 0 14:35:49 INFO - grabbing minidump binary from tooltool 14:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:35:49 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 0x18813b0>, '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 0x18822b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:35:49 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 14:35:49 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 14:35:49 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 14:35:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:35:49 INFO - Return code: 0 14:35:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:35:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:35:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:35:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:35:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:35:49 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/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 14:35:49 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/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 14:35:49 INFO - Using env: {'DISPLAY': ':0', 14:35:49 INFO - 'HOME': '/home/cltbld', 14:35:49 INFO - 'LANG': 'en_US.UTF-8', 14:35:49 INFO - 'LANGUAGE': 'en_US:en', 14:35:49 INFO - 'LOGNAME': 'cltbld', 14:35:49 INFO - 'MAIL': '/var/mail/cltbld', 14:35:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:35:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:35:49 INFO - 'MOZ_NO_REMOTE': '1', 14:35:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:35:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:35:49 INFO - 'NO_EM_RESTART': '1', 14:35:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:35:49 INFO - 'PWD': '/builds/slave/test', 14:35:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:35:49 INFO - 'SHELL': '/bin/bash', 14:35:49 INFO - 'SHLVL': '1', 14:35:49 INFO - 'TERM': 'linux', 14:35:49 INFO - 'TMOUT': '86400', 14:35:49 INFO - 'USER': 'cltbld', 14:35:49 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471901249.97257-45535204', 14:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:35:49 INFO - '_': '/tools/buildbot/bin/python'} 14:35:49 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/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 14:35:49 INFO - mozversion application_buildid: 20160822113013 14:35:49 INFO - mozversion application_changeset: 7b44f5b3b0bb503b79166ac861b1f3f9c1f8abb9 14:35:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:35:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:35:49 INFO - mozversion application_name: Firefox 14:35:49 INFO - mozversion application_remotingname: firefox-dev 14:35:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:35:49 INFO - mozversion application_vendor: Mozilla 14:35:49 INFO - mozversion application_version: 50.0a2 14:35:49 INFO - mozversion platform_buildid: 20160822113013 14:35:49 INFO - mozversion platform_changeset: 7b44f5b3b0bb503b79166ac861b1f3f9c1f8abb9 14:35:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:35:49 INFO - mozversion platform_version: 50.0a2 14:35:49 INFO - using testdate: 1471901749 14:35:49 INFO - actual date: 1471901749 14:35:49 INFO - starting webserver on 'localhost:55336' 14:35:49 INFO - SUITE-START | Running 2 tests 14:35:49 INFO - TEST-START | dromaeo_css 14:35:49 INFO - operating with platform_type : linux_ 14:35:49 INFO - Initialising browser for dromaeo_css test... 14:35:49 INFO - TEST-INFO | started process 21805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp84v6Hm/profile http://localhost:55336/getInfo.html) 14:35:53 INFO - PROCESS | 21805 | __metrics Screen width/height:1600/1200 14:35:53 INFO - PROCESS | 21805 | colorDepth:24 14:35:53 INFO - PROCESS | 21805 | Browser inner width/height: 1024/697 14:35:53 INFO - PROCESS | 21805 | __metrics 14:35:54 INFO - PROCESS | 21805 | 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" 14:35:54 INFO - PROCESS | 21805 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:35:55 INFO - TEST-INFO | 21805: exit 0 14:35:55 INFO - Browser initialized. 14:35:55 INFO - Running cycle 1/1 for dromaeo_css test... 14:35:55 INFO - TEST-INFO | started process 21893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp84v6Hm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:36:07 INFO - PROCESS | 21893 | RSS: Main: 145121280 14:36:07 INFO - PROCESS | 21893 | 14:36:57 INFO - PROCESS | 21893 | 1471901817855 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] 14:39:06 INFO - PROCESS | 21893 | Cycle 1(1): loaded http://localhost:55336/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55336/tests/dromaeo/cssquery-ext.html) 14:39:07 INFO - PROCESS | 21893 | RSS: Main: 221360128 14:39:07 INFO - PROCESS | 21893 | 14:42:10 INFO - PROCESS | 21893 | Cycle 1(1): loaded http://localhost:55336/tests/dromaeo/cssquery-ext.html (next: http://localhost:55336/tests/dromaeo/cssquery-jquery.html) 14:42:10 INFO - PROCESS | 21893 | RSS: Main: 224796672 14:42:10 INFO - PROCESS | 21893 | 14:45:12 INFO - PROCESS | 21893 | Cycle 1(1): loaded http://localhost:55336/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55336/tests/dromaeo/cssquery-mootools.html) 14:45:13 INFO - PROCESS | 21893 | RSS: Main: 176205824 14:45:13 INFO - PROCESS | 21893 | 14:48:15 INFO - PROCESS | 21893 | Cycle 1(1): loaded http://localhost:55336/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55336/tests/dromaeo/cssquery-prototype.html) 14:48:15 INFO - PROCESS | 21893 | RSS: Main: 174698496 14:48:15 INFO - PROCESS | 21893 | 14:51:18 INFO - PROCESS | 21893 | Cycle 1(1): loaded http://localhost:55336/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55336/tests/dromaeo/cssquery-yui.html) 14:51:18 INFO - PROCESS | 21893 | RSS: Main: 177004544 14:51:18 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | Cycle 1(1): loaded http://localhost:55336/tests/dromaeo/cssquery-yui.html (next: http://localhost:55336/tests/dromaeo/cssquery-dojo.html) 14:54:21 INFO - PROCESS | 21893 | RSS: Main: 263995392 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | __start_tp_report 14:54:21 INFO - PROCESS | 21893 | _x_x_mozilla_page_load 14:54:21 INFO - PROCESS | 21893 | _x_x_mozilla_page_load_details 14:54:21 INFO - PROCESS | 21893 | |i|pagename|runs| 14:54:21 INFO - PROCESS | 21893 | |0;dojo.html;3219.3883162199186;3239.3683231769814;3251.837408129593;3266.1018219989487;3287.3753986742518;5597.944020559793;5656.886862262752;5684.516816070639;5689.800856969997;5695.373508914016;5651.943480565194;5722.513586345155;5733.254676891998;5748.080307150846;5766.9711651441785;4903.583195428375;4943.480934501879;5191.247269145972;5197.428282888879;5223.294855194557;5068.518490743373;5096.770645320969;5098.413682426512;5101.234814777795;5107.923381149286;5017.673851199678;5073.441921388644;5078.035173317058;5085;5100.566451851597;5135.947130838169;5158.92261616076;5160.329157209558;5161.66449180802;5181.533661970422;4966.801327946878;5049.596032317406;5066;5091.643584949055;5118.462561431071;5512.862178445531;5526.50261476469;5529.723513824292;5632.07070833312;5635.323761148688;6062.927496580028;6089.969550152221;6097.634141951497;6101.450869421729;6102.72537735803;5909.231799866033;5964.075568286922;5987.700614969234;6082.5742198046155;6119.694015299262;5626.887462250732;5661.320641523002;5669.8299051028125;5675.404082571353;5675.772969081273;5964.224650795369;6133.570650054543;6140.854922927007;6147.538934579924;6148.815535533941;5933.792317268875;5960.225170727865;5974;6073.271207455045;6090.908636370458;6124.425803874611;6132.63204207749;6134.938650613437;6143.232095988002;6145.139680444741;5763.423657634203;6117.398686497024;6119.562077244144;6134.436587033385;6134.987697083746;5998.130271110665;6053.818385448443;6057.7879774207695;6116.082587611894;6123.326434092246;4674.555917187861;4677.22825733757;4679.087577922341;4679.3916790816975;4706.599939005155;4055.351143817034;4139.544650088488;4175.206710724952;4185.665146788249;4197.3284274516545;5662.886742265132;5716.114002329646;5723.685197314105;5765.250517432707;5777.2200752897825;6104.9109929343795;6110.847228819226;6110.969445152835;6111.419415155553;6117.480014198754;5673.517750991129;5694.971525142348;5732.168835518826;5744.655320680772;5748.540116790647;4939;5005.449400565934;5086.262491938722;5122.795088196505;5198.220266959911;6337.2395312562785;6374.266959299647;6375.553711240169;6376.362363763679;6377.3303803100935;6075.635461872302;6107.84730381744;6129.724162412613;6130.325664176848;6143.938560614337;6074.36219196978;6089.451949324582;6095.05526643371;6110.969445152746;6206.348333424925;5903.498202652822;5907.143464197581;5912.379200184091;5917;5951.256092988377;6017.909731353946;6018.037114061729;6027.126066720389;6033;6034.517238620988;5746;5832.883342333214;5872;5882.764689412375;5964.761409543743;5927.051671732502;5932.0508718604815;5934;5934.762609495572;5937.7921772737745;5828.329742079715;5844.620099693507;5876.911846322223;5879.912216240009;5897.498712609477;5792.160136780228;5880.882382352414;5949;5951.434613711691;6030.6080104793045;5841.299044114563;5858.443447872445;5864.296284445893;5867.149263356875;5868.061110222344 14:54:21 INFO - PROCESS | 21893 | |1;ext.html;619.8853212155749;626.2516293029828;637.5314144104083;649.8927676933306;663.767681311541;35123.47314790277;46484;47864.76067619666;47869.76065119678;51569.48430515694;49540.50459495413;49912.50087499124;50026.49973500264;50067.74966125164;50093.24860127101;8257.628406721682;9167.862482062757;9313.90686093137;9325.487098209596;10929.344239345664;10721.463926803668;11170.106391488662;11175.944120279388;11213.831792523119;11215.327080375204;11189.384583847885;11278.943605282004;11289.040431563286;11292.322460652387;11293.661190164308;8378.162183781635;10854.45727713619;11210.831837522444;11244.100471962223;11279.605213817518;11083.334999900031;11171.050460710769;11175.161862860237;11177.049950754195;11180.04969577587;45971.310430343234;46373;46375.30437043447;46379.5362046382;46383;3679.8528058877614;3763.7741735495683;3887.9222415551812;4024.1549274652357;4319.308910574325;35064;35310.16557375441;35403.64596354029;35497;36041.819790900874;4665.6967297125575;4675.4155730533685;4701.788419521095;4711.3639658646325;4743.398504342791;394711;396064;396583;399484;399564;63501;63543.6822815883;63769.68115159487;64102.67948660227;64197.67901160464;39393;40060.79969600133;40257;42564.36153457701;67583;19872;20161.294354697515;20175;20175.092120854595;20175.39473815788;13934.93032534851;14350.856491434952;14364.784528231876;14706.852931470763;15237.314320855588;20531.4867128323;21289.78710212878;21307.68038479424;22196.22313219062;22305.44236394103;7637.350825179811;7723.073231212291;7801.9609901950125;7830.530168189927;7964.800879978047;1019.7960407918298;1048.4600430778155;1104.1718710966775;1158.391844281742;1229.6329565586211;31523.527147092813;34074.31851362959;34671.479927801105;34754.30491390158;34989.82505087509;1185.9051275897907;1195.8863907928908;1225.4485481533345;1235.7837378458735;1291.9935400323127;17965.371212007518;17992.730109048374;18026.188821503063;18037.639247215244;18301.7254741179;623172;623390;628850;630204;631466;65713.34286657072;66325.33674663288;66368.33631663621;66467.33532664612;66633.33366666271;1363.8908887288992;1445.2918070145763;1501.542029680936;1521.0341433189783;1521.9239038048054;34307;35812.03971495928;36517.81741091277;37232.25535489221;38326;64488.67755661192;65210.67394662996;65261;65339.673301633185;65478.67260663666;544486;545217;546931;549661;551414;45346.31980520361;49003.75498122487;49215.50784492109;49302;49463;54981.72509137429;55074;55158;55211.723941380034;55955.72022139863;44733.329000064376;46501.76749116368;47272.7636361816;47753.761231193625;48143;45943.31085033794;46395.30407043965;46502.76748616235;46579.767101165635;47619.523804761506;46168;46373.07253854971;46530.767346163055;46624.53375466202;46831.76584117058;36927.630723693495;40701;41251.38122928098;41753.58246417619;41958.580414195465;30928.845355773075;31081.844590776902;31105.8444707775;31235;31612.209694757814 14:54:21 INFO - PROCESS | 21893 | |2;jquery.html;404.0726532607665;408.36906978717894;423.76057527496954;426.99359509607353;440.4384409877408;15356.539303820875;20001.599968000668;20535;20648.793512064894;20789.896050519746;19566.90216548915;19572.70640940387;19632.116554755034;19650.50873728154;19862.40412787615;5855.824325270228;5899.675517846516;5900.203472531218;5996.190514280582;6014.1580178775;5972.611780234292;6016.157737916695;6101.023836186211;6112.908306375408;6114.69426528674;5815.098659707752;5957;6013.548983826208;6048.909266361008;6090.269167699882;5911.438413350767;5960.970195148997;5974.731137098841;6003.789867354665;6021.9698901505435;5225.6603320784025;5355.250264962909;5355.973220133913;5357.116075847481;5360.544353729938;16450.177491125396;21003.894980525154;21687.45781355463;21775.891120544296;23361.065557377686;2372.3238876919995;2467.864267465289;2575.9098431554808;2584.5735453650127;3099.7210251077513;14931.25343732809;14962.17708026058;15641.217939102999;15667.373305067784;15733.213339332988;2056.609244243589;2065.132644289387;2077.4390914453165;2082.1775398717573;2132.72274604302;80686.98282542983;87567.56216218878;89337;89680.55159724224;90239.54880225622;11685.415729213506;11752.706182345339;11875.70310742221;11900.107491938139;12056.87943120575;16744.665106697797;16757;16760.832391675925;16781.748273775902;16789.076600787073;7869.2130786922;8300.626471808782;8771.298296136294;8841.734747957571;8898.243649289883;8553.871691924625;8724.520151391607;8832.602532886036;8908.688195913111;8913.688020919237;5003.224500202475;5009.624278179152;5027.597792176617;5032.521910418504;5115.488451154929;2054.979450205509;2080.9479763006048;2094.214669498938;2101.737282839645;2147.194801949269;945.8912225094065;991.2218908157062;1029.6807989523122;1071.137734124022;1112.1714322829544;19913.900430497753;20442.488937776674;20546;20630.793692063187;20690.896545517477;1051.4344998204356;1066.7599790047177;1071.9732006699894;1073.75303680154;1079.3254216114929;10197.490125493696;11377.715557110972;11395.14536409756;11432.485538150802;11455.427228638535;570010;570815;572206;574792;590590;91000;95378;96720.54919176218;99056.50471747595;100720;988.4761076629397;1020.2297265564451;1026.6714651311509;1037.0821822686976;1039.0804138337473;17127.143642818057;17846.91076544609;18159.727604085685;18309.084545772923;18376.90811545987;40995;42026;42233.36649950314;43187;43651.56348436475;53681.73159134179;60207.69896150491;60552.39447605467;61377.38622613895;62737;8085.272325490734;8412.747617571482;8509;8980.910190898006;9244.768880778034;10293.74265643365;13904.165750055028;13998.37007334652;14438;14527.27363631793;11661.883381166079;11924.701882453008;12188.57339993096;12256.325902075472;12455.875441245471;10861.013221364188;12007.339596322287;12057.638270851889;12139.817902731289;12232.755344892874;11874.703132421759;12026.692659518701;12201.633950981484;12234.87765122373;12601.873981260436;7941.166177551274;8133.14601966785;8160;8391.832163356577;8544.40189186751;9749.951250243703;11867.703307417383;11987.40062996864;12240.816387754368;12299.93850030744 14:54:21 INFO - PROCESS | 21893 | |3;mootools.html;1448.637840539865;1535.1710076558659;1604.5747876812648;1760.2606905099856;1875.7655293088364;8150.673973041079;9193.540322983858;9614;9630.13328800407;9751.70744877653;9419.152276295132;9460.526973651324;9467.715968520939;9470.66852660157;9488.66789662362;2467.346153269385;2513.070164039299;2513.3465299022296;2516.7860731837814;2520.3573088862313;2355.305184970438;2432.74456182101;2456.533258680854;2462.372095115743;2467.4201562632857;2422.5033868057008;2437.987810060947;2465.4822487277693;2475.3687809608614;2486.29140694902;2354.5526349993534;2378.8929498172624;2384.5469360821476;2427.9028838846425;2431.489387228684;2377.679013333195;2387.8089752819733;2387.820913431482;2388.1880160745254;2389.7012873390827;9373.484458354787;9532.428054316762;9546;9572.282078844044;9579.952100239454;5688.886222275572;5909.527237820964;5922.763089476416;5925.844460330238;5946.197263369433;3499.860005599773;3500.702440292578;3538.380783362927;3556.270964452281;3564.6276183669215;1211.7818792617413;1219.0613227814622;1225.8038713805838;1256.2462522486526;1258.7104965857811;13744.793828092586;13939.860601393957;13940.372683229178;14019.439222431192;14032.08791428554;4918.9508104919205;4931.753412329369;4944.629152813553;4984.576311013532;5167.199084141964;10532.947335263274;10540.209484288709;10551;10558.577656893638;10565.418901960466;5668.943310566841;5679.403662615448;5700.6009579329875;5715.199872017921;5717.028105222123;4731.668783185144;4753.073150735573;5253.264542963988;5305.389880163831;5326.2543243945875;4267.935980960288;4299.828006879752;4308.5045219799495;4351.238533256667;4471.709338893027;1282.6344491819787;1324.218710960538;1329.9601011969658;1332.360466975857;1347.3061373392711;989.8564708117283;1064.8296272596347;1094.950727217277;1095.3209010413595;1101.691526372601;6616.106825578581;6658.43403310724;6692.899606505906;6865.828354291183;6867.381935625817;510.81610620176707;515.2245869965752;519.4441947116625;524.7376311844077;526.6471464119049;4662.7202367858035;4677.251639737626;4691.225947718656;4734.21885388907;4816.349792778;11541;11548.076153907667;12013.459394327276;12032.578859740042;12078.879211207952;11665.76668466626;11901.226420282826;11921;11936.761264774656;11938.403079845973;8884.777880553038;8925.285977121815;8943.926728792456;8994.550272486349;9048.230900373543;6513.902291465537;6583.34166583357;6642.535022548375;6644.468442524683;6664.400203981665;10771.19216058783;10818.783624327627;10994.395308258123;11106.222564420414;11140.944295278796;9988.200943924323;10702.357858528514;11135.053520450852;11188.88811111911;11271.041961433042;7846.215378462336;8048.315893149148;8062.677492900219;8091.352691784526;8101.635426405944;9028.683996060106;9051.185393314634;9098.863517047383;9101.317401194805;9104.18062374363;8182.4681395709085;8409.831803363775;8505.489670619783;8534.829303413773;8658;7999.720009799629;8110.351171906115;8201.630926608435;8279.62741676638;8618.525981071101;7273.3817625500305;7393.408527317909;7519.736809211651;7609.124950630527;7610.847783044419;5583.776648934159;5625.268715067015;5787.5369970401425;5789.56578256624;5896.55775816824;5885.941140588539;5894.6757928314355;5916.881662366814;5954;6145.754169833157 14:54:21 INFO - PROCESS | 21893 | |4;prototype.html;354.4258301551487;359.3604971484447;363.51833820188256;366.15418383534023;366.90827293176704;5458;5580.3816893101;5595.160725891118;5599.1041433370665;5622.859428514289;5441.2654291670615;5451.318585176853;5481.177193745224;5490.972545137269;5507.504324610784;4154.418381426595;4306.418633484486;4308.676849236304;4332.350147477872;4353.62994145498;4269.316909294514;4290.635295999844;4315.180115778008;4331.852059204298;4350.956490435086;4312.353147027955;4324.654027677794;4331.0255192581735;4332.4151239582725;4334.804933777972;4167.854125105622;4284.564670835242;4290.163418133465;4312.5903039211225;4330.6968512204;3803.5055442792536;3810.2760475509563;3815.6184381561898;3816.1985982943615;3827.789471579062;5390.460953904618;5421.457854214586;5426.565874730012;5444.40111587725;5466.617336786427;4683.250679891201;4888.364512613373;4942.604591632697;4944.678595891256;4983.302337672729;5597.552195824324;5710.8001219957105;5715.971420142914;5717.142428635696;5720.828375148711;4128.938065929014;4137.8758637240935;4142.026623743418;4161.189882401135;4183.602557757008;5852.941470585325;5883.441073098049;5885;5888.97055514724;6014.8195554133445;4256.425382573375;4261.936070958938;4264.829406823692;4275.957240427618;4287.442632457761;4324.610785029363;4326.24290749124;4328.43730315057;4329.242382583098;4335.1329734053315;4757.643176761757;4857.53853383928;4865.829695960695;4896.5837903777865;4915.3855768029;5690.9146362804595;5703.287089113861;5707.172459993278;5708.914366284509;5713.200151978726;1454.789055586934;1485.1311982490365;1487;1489.5010171592637;1494.9103053816805;925.9166674999283;973.352720440913;973.5375696544227;974.4640447753849;982.9826129955604;4880.389951256093;4909.2881532177635;4914.754262286943;4942.703437793743;4979.551840334388;5104.744762761847;5125.89793194465;5147.3308469898675;5173.689578625294;5212.218167274939;4649.697769644962;4939.629527785359;4945.8021679132435;4978.726170060681;4980.676256043345;2715.782737381044;2739.4794988952035;2765.128984369917;2766.7509924106926;2767.4741799058115;5284.4187139414635;5309.65487243328;5311.336082989626;5346.251524786533;5351.795845934634;5281.973590132102;5297.973510132425;5586.55307575393;5673.716314184274;5928.555358348141;5890.2342695449315;6003.5797494174985;6068.180795592539;6109.786157484378;6156.722947467375;5881.323822710507;5890.43903365617;5893.469587736953;5995.790147344822;6001.5798894077;5602.663840169603;5719.8570035749435;5844.6785426801935;5869.8239052828485;5887.4406931341455;6065.272167339947;6107.144999700135;6164.537659675452;6172.783952561818;6173.629582225081;4175.749455032707;4331.848385306499;4368.716033457815;4384.430024096847;4393.29707246839;5854.443827836349;5858.7070646468355;5868.503531599415;5877.882442351043;5889.381614930542;5680.9548562518285;5718.714064296685;5725.307176870978;5749;5782.45066718661;5647.20939068539;5701.173329867229;5723.255976723;5733.197352370755;5789.623674461147;5632.662040277596;5637.830865074055;5641.605087643826;5687.260656114679;5690.886182276414;5634.830955071355;5711.257536520226;5770.2498675171955;5790.552361909523;5807;5592.5525957922455;5606.943930560642;5608.523275521627;5637.915431268616;5644.294463192101 14:54:21 INFO - PROCESS | 21893 | |5;yui.html;810.009617005245;810.2990912860374;848.2832006954123;856.1267507142715;872.4198408058642;15978.68042639148;17421.912890435546;17430.128493575303;17449.476515704533;17467.039312837787;15581.922090389533;15784.289706963185;15795.131267780267;15809.288582013809;15823.920880395583;714.8427345983876;735.9668814903315;739.2718172599984;741.2735519191195;843.5191940593865;877.5787621941472;890.3900827932887;897.8787863638423;899.8605216191468;900.3877363392891;930.6928713524554;937.8686983822271;941.0119374656617;941.2611100286276;943.1511639524414;755.4749449132855;941.1858742187992;941.2140862379887;941.29873244433;941.4868896451451;659.4888961055173;660.1780782925241;665.7037618259876;680.6664734280216;690.2545251128769;15366.154861482613;15397.692046159014;15428.3828646854;15431.459898903597;15663.608409789733;14968.251587420586;15573.454929077428;15635.530934071996;15703;15773.684526309524;14472.565823025327;14487.71024579513;14492.782608260884;14517.491887783983;14783.630409239748;4676.18166820805;4698.671093023489;4700.764961751899;4789.832355867563;4803.423589169322;87209.12790872074;87805;88691;93716;94512.52743736307;11054;11059.281146725438;11124.33254004746;11285.492152853141;11446.771064578661;21853.453663658216;21976.560468790536;22079;22123.446913827283;22210.444738881335;8912.777180570409;9039.23166530839;9239.76900577491;9247.953760231156;9381.577828997712;5007.24891266313;5089.720065396365;5126.154184559506;5132.820351287687;5246.370435547682;3714.0529165062735;4180.811863466151;4296.398504209413;4326.048269380732;4347.456567929009;690.4959379652781;700.3766647683566;700.8458139209366;704.6828926982776;706.5372181221309;690.268315585481;736.7421402509186;769.5190505933791;798.4371018431991;822.42841225349;9789.853152202722;9841.901580984242;9958.502074896227;10281.948590257001;10323.32898361604;783.9960800195962;807.9838403231903;808.5229714468492;824.3034635732796;846.703653721205;4534.682572219979;4674.57928786404;4733.148033354029;4795.040991801654;4806.014766972832;104263.43604845933;128420;129967;137542;140871;115882;117203.41398292955;119736;120164;122599;103838.48080759548;104872;105834;106015;107823;8589.914100859161;8776.16626420489;8822.5588720565;8861.822763544822;8967.389955371662;59325.40674593372;60304;60484;60591.39408605857;61076;86071;87229;87320;89297.55351223203;90153.54923225341;13016.414261358452;13176.341182941007;13218.867811321763;13364.732705346034;13654.658633534242;14430.639234019234;14491.637709057359;14893.574676842836;14970.775438368652;15616.375344986074;12759.106862519535;12797.360131993177;13197.538086166938;13262.734745305233;13319.467221311039;13058.934705326412;13543.729125417634;13562;13831.930840345734;14120.223387713364;12430;12491.562795302121;12649.177803442746;12763.170393924365;13454;10378.481075946323;10516.491907511456;10595.364278143335;10723.94638026805;11016;10003.399796012263;10829.945850270698;10840.891591084304;10847.13222942147;10851.728706782395 14:54:21 INFO - PROCESS | 21893 | __end_tp_report 14:54:21 INFO - PROCESS | 21893 | __start_cc_report 14:54:21 INFO - PROCESS | 21893 | _x_x_mozilla_cycle_collect,1051 14:54:21 INFO - PROCESS | 21893 | __end_cc_report 14:54:21 INFO - PROCESS | 21893 | __startTimestamp1471902861476__endTimestamp 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | ------- Summary: start ------- 14:54:21 INFO - PROCESS | 21893 | Number of tests: 6 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | [#0] dojo.html Cycles:165 Average:5614.28 Median:5858.44 stddev:648.45 (11.1%) stddev-sans-first:622.63 14:54:21 INFO - PROCESS | 21893 | Values: 3219.4 3239.4 3251.8 3266.1 3287.4 5597.9 5656.9 5684.5 5689.8 5695.4 5651.9 5722.5 5733.3 5748.1 5767.0 4903.6 4943.5 5191.2 5197.4 5223.3 5068.5 5096.8 5098.4 5101.2 5107.9 5017.7 5073.4 5078.0 5085.0 5100.6 5135.9 5158.9 5160.3 5161.7 5181.5 4966.8 5049.6 5066.0 5091.6 5118.5 5512.9 5526.5 5529.7 5632.1 5635.3 6062.9 6090.0 6097.6 6101.5 6102.7 5909.2 5964.1 5987.7 6082.6 6119.7 5626.9 5661.3 5669.8 5675.4 5675.8 5964.2 6133.6 6140.9 6147.5 6148.8 5933.8 5960.2 5974.0 6073.3 6090.9 6124.4 6132.6 6134.9 6143.2 6145.1 5763.4 6117.4 6119.6 6134.4 6135.0 5998.1 6053.8 6057.8 6116.1 6123.3 4674.6 4677.2 4679.1 4679.4 4706.6 4055.4 4139.5 4175.2 4185.7 4197.3 5662.9 5716.1 5723.7 5765.3 5777.2 6104.9 6110.8 6111.0 6111.4 6117.5 5673.5 5695.0 5732.2 5744.7 5748.5 4939.0 5005.4 5086.3 5122.8 5198.2 6337.2 6374.3 6375.6 6376.4 6377.3 6075.6 6107.8 6129.7 6130.3 6143.9 6074.4 6089.5 6095.1 6111.0 6206.3 5903.5 5907.1 5912.4 5917.0 5951.3 6017.9 6018.0 6027.1 6033.0 6034.5 5746.0 5832.9 5872.0 5882.8 5964.8 5927.1 5932.1 5934.0 5934.8 5937.8 5828.3 5844.6 5876.9 5879.9 5897.5 5792.2 5880.9 5949.0 5951.4 6030.6 5841.3 5858.4 5864.3 5867.1 5868.1 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | [#1] ext.html Cycles:180 Average:70151.83 Median:41502.48 stddev:141445.42 (340.8%) stddev-sans-first:141745.87 14:54:21 INFO - PROCESS | 21893 | Values: 619.9 626.3 637.5 649.9 663.8 35123.5 46484.0 47864.8 47869.8 51569.5 49540.5 49912.5 50026.5 50067.7 50093.2 8257.6 9167.9 9313.9 9325.5 10929.3 10721.5 11170.1 11175.9 11213.8 11215.3 11189.4 11278.9 11289.0 11292.3 11293.7 8378.2 10854.5 11210.8 11244.1 11279.6 11083.3 11171.1 11175.2 11177.0 11180.0 45971.3 46373.0 46375.3 46379.5 46383.0 3679.9 3763.8 3887.9 4024.2 4319.3 35064.0 35310.2 35403.6 35497.0 36041.8 4665.7 4675.4 4701.8 4711.4 4743.4 394711.0 396064.0 396583.0 399484.0 399564.0 63501.0 63543.7 63769.7 64102.7 64197.7 39393.0 40060.8 40257.0 42564.4 67583.0 19872.0 20161.3 20175.0 20175.1 20175.4 13934.9 14350.9 14364.8 14706.9 15237.3 20531.5 21289.8 21307.7 22196.2 22305.4 7637.4 7723.1 7802.0 7830.5 7964.8 1019.8 1048.5 1104.2 1158.4 1229.6 31523.5 34074.3 34671.5 34754.3 34989.8 1185.9 1195.9 1225.4 1235.8 1292.0 17965.4 17992.7 18026.2 18037.6 18301.7 623172.0 623390.0 628850.0 630204.0 631466.0 65713.3 66325.3 66368.3 66467.3 66633.3 1363.9 1445.3 1501.5 1521.0 1521.9 34307.0 35812.0 36517.8 37232.3 38326.0 64488.7 65210.7 65261.0 65339.7 65478.7 544486.0 545217.0 546931.0 549661.0 551414.0 45346.3 49003.8 49215.5 49302.0 49463.0 54981.7 55074.0 55158.0 55211.7 55955.7 44733.3 46501.8 47272.8 47753.8 48143.0 45943.3 46395.3 46502.8 46579.8 47619.5 46168.0 46373.1 46530.8 46624.5 46831.8 36927.6 40701.0 41251.4 41753.6 41958.6 30928.8 31081.8 31105.8 31235.0 31612.2 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | [#2] jquery.html Cycles:180 Average:32098.12 Median:22568.48 stddev:94656.06 (419.4%) stddev-sans-first:94891.68 14:54:21 INFO - PROCESS | 21893 | Values: 404.1 408.4 423.8 427.0 440.4 15356.5 20001.6 20535.0 20648.8 20789.9 19566.9 19572.7 19632.1 19650.5 19862.4 5855.8 5899.7 5900.2 5996.2 6014.2 5972.6 6016.2 6101.0 6112.9 6114.7 5815.1 5957.0 6013.5 6048.9 6090.3 5911.4 5961.0 5974.7 6003.8 6022.0 5225.7 5355.3 5356.0 5357.1 5360.5 16450.2 21003.9 21687.5 21775.9 23361.1 2372.3 2467.9 2575.9 2584.6 3099.7 14931.3 14962.2 15641.2 15667.4 15733.2 2056.6 2065.1 2077.4 2082.2 2132.7 80687.0 87567.6 89337.0 89680.6 90239.5 11685.4 11752.7 11875.7 11900.1 12056.9 16744.7 16757.0 16760.8 16781.7 16789.1 7869.2 8300.6 8771.3 8841.7 8898.2 8553.9 8724.5 8832.6 8908.7 8913.7 5003.2 5009.6 5027.6 5032.5 5115.5 2055.0 2080.9 2094.2 2101.7 2147.2 945.9 991.2 1029.7 1071.1 1112.2 19913.9 20442.5 20546.0 20630.8 20690.9 1051.4 1066.8 1072.0 1073.8 1079.3 10197.5 11377.7 11395.1 11432.5 11455.4 570010.0 570815.0 572206.0 574792.0 590590.0 91000.0 95378.0 96720.5 99056.5 100720.0 988.5 1020.2 1026.7 1037.1 1039.1 17127.1 17846.9 18159.7 18309.1 18376.9 40995.0 42026.0 42233.4 43187.0 43651.6 53681.7 60207.7 60552.4 61377.4 62737.0 8085.3 8412.7 8509.0 8980.9 9244.8 10293.7 13904.2 13998.4 14438.0 14527.3 11661.9 11924.7 12188.6 12256.3 12455.9 10861.0 12007.3 12057.6 12139.8 12232.8 11874.7 12026.7 12201.6 12234.9 12601.9 7941.2 8133.1 8160.0 8391.8 8544.4 9750.0 11867.7 11987.4 12240.8 12299.9 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | [#3] mootools.html Cycles:180 Average:6271.26 Median:4925.35 stddev:3601.71 (73.1%) stddev-sans-first:3593.57 14:54:21 INFO - PROCESS | 21893 | Values: 1448.6 1535.2 1604.6 1760.3 1875.8 8150.7 9193.5 9614.0 9630.1 9751.7 9419.2 9460.5 9467.7 9470.7 9488.7 2467.3 2513.1 2513.3 2516.8 2520.4 2355.3 2432.7 2456.5 2462.4 2467.4 2422.5 2438.0 2465.5 2475.4 2486.3 2354.6 2378.9 2384.5 2427.9 2431.5 2377.7 2387.8 2387.8 2388.2 2389.7 9373.5 9532.4 9546.0 9572.3 9580.0 5688.9 5909.5 5922.8 5925.8 5946.2 3499.9 3500.7 3538.4 3556.3 3564.6 1211.8 1219.1 1225.8 1256.2 1258.7 13744.8 13939.9 13940.4 14019.4 14032.1 4919.0 4931.8 4944.6 4984.6 5167.2 10532.9 10540.2 10551.0 10558.6 10565.4 5668.9 5679.4 5700.6 5715.2 5717.0 4731.7 4753.1 5253.3 5305.4 5326.3 4267.9 4299.8 4308.5 4351.2 4471.7 1282.6 1324.2 1330.0 1332.4 1347.3 989.9 1064.8 1095.0 1095.3 1101.7 6616.1 6658.4 6692.9 6865.8 6867.4 510.8 515.2 519.4 524.7 526.6 4662.7 4677.3 4691.2 4734.2 4816.3 11541.0 11548.1 12013.5 12032.6 12078.9 11665.8 11901.2 11921.0 11936.8 11938.4 8884.8 8925.3 8943.9 8994.6 9048.2 6513.9 6583.3 6642.5 6644.5 6664.4 10771.2 10818.8 10994.4 11106.2 11140.9 9988.2 10702.4 11135.1 11188.9 11271.0 7846.2 8048.3 8062.7 8091.4 8101.6 9028.7 9051.2 9098.9 9101.3 9104.2 8182.5 8409.8 8505.5 8534.8 8658.0 7999.7 8110.4 8201.6 8279.6 8618.5 7273.4 7393.4 7519.7 7609.1 7610.8 5583.8 5625.3 5787.5 5789.6 5896.6 5885.9 5894.7 5916.9 5954.0 6145.8 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | [#4] prototype.html Cycles:180 Average:4757.70 Median:5349.02 stddev:1386.53 (25.9%) stddev-sans-first:1350.46 14:54:21 INFO - PROCESS | 21893 | Values: 354.4 359.4 363.5 366.2 366.9 5458.0 5580.4 5595.2 5599.1 5622.9 5441.3 5451.3 5481.2 5491.0 5507.5 4154.4 4306.4 4308.7 4332.4 4353.6 4269.3 4290.6 4315.2 4331.9 4351.0 4312.4 4324.7 4331.0 4332.4 4334.8 4167.9 4284.6 4290.2 4312.6 4330.7 3803.5 3810.3 3815.6 3816.2 3827.8 5390.5 5421.5 5426.6 5444.4 5466.6 4683.3 4888.4 4942.6 4944.7 4983.3 5597.6 5710.8 5716.0 5717.1 5720.8 4128.9 4137.9 4142.0 4161.2 4183.6 5852.9 5883.4 5885.0 5889.0 6014.8 4256.4 4261.9 4264.8 4276.0 4287.4 4324.6 4326.2 4328.4 4329.2 4335.1 4757.6 4857.5 4865.8 4896.6 4915.4 5690.9 5703.3 5707.2 5708.9 5713.2 1454.8 1485.1 1487.0 1489.5 1494.9 925.9 973.4 973.5 974.5 983.0 4880.4 4909.3 4914.8 4942.7 4979.6 5104.7 5125.9 5147.3 5173.7 5212.2 4649.7 4939.6 4945.8 4978.7 4980.7 2715.8 2739.5 2765.1 2766.8 2767.5 5284.4 5309.7 5311.3 5346.3 5351.8 5282.0 5298.0 5586.6 5673.7 5928.6 5890.2 6003.6 6068.2 6109.8 6156.7 5881.3 5890.4 5893.5 5995.8 6001.6 5602.7 5719.9 5844.7 5869.8 5887.4 6065.3 6107.1 6164.5 6172.8 6173.6 4175.7 4331.8 4368.7 4384.4 4393.3 5854.4 5858.7 5868.5 5877.9 5889.4 5681.0 5718.7 5725.3 5749.0 5782.5 5647.2 5701.2 5723.3 5733.2 5789.6 5632.7 5637.8 5641.6 5687.3 5690.9 5634.8 5711.3 5770.2 5790.6 5807.0 5592.6 5606.9 5608.5 5637.9 5644.3 14:54:21 INFO - PROCESS | 21893 | 14:54:21 INFO - PROCESS | 21893 | [#5] yui.html Cycles:180 Average:23511.44 Median:4336.75 stddev:35448.34 (817.4%) stddev-sans-first:35506.80 14:54:21 INFO - PROCESS | 21893 | Values: 810.0 810.3 848.3 856.1 872.4 15978.7 17421.9 17430.1 17449.5 17467.0 15581.9 15784.3 15795.1 15809.3 15823.9 714.8 736.0 739.3 741.3 843.5 877.6 890.4 897.9 899.9 900.4 930.7 937.9 941.0 941.3 943.2 755.5 941.2 941.2 941.3 941.5 659.5 660.2 665.7 680.7 690.3 15366.2 15397.7 15428.4 15431.5 15663.6 14968.3 15573.5 15635.5 15703.0 15773.7 14472.6 14487.7 14492.8 14517.5 14783.6 4676.2 4698.7 4700.8 4789.8 4803.4 87209.1 87805.0 88691.0 93716.0 94512.5 11054.0 11059.3 11124.3 11285.5 11446.8 21853.5 21976.6 22079.0 22123.4 22210.4 8912.8 9039.2 9239.8 9248.0 9381.6 5007.2 5089.7 5126.2 5132.8 5246.4 3714.1 4180.8 4296.4 4326.0 4347.5 690.5 700.4 700.8 704.7 706.5 690.3 736.7 769.5 798.4 822.4 9789.9 9841.9 9958.5 10281.9 10323.3 784.0 808.0 808.5 824.3 846.7 4534.7 4674.6 4733.1 4795.0 4806.0 104263.4 128420.0 129967.0 137542.0 140871.0 115882.0 117203.4 119736.0 120164.0 122599.0 103838.5 104872.0 105834.0 106015.0 107823.0 8589.9 8776.2 8822.6 8861.8 8967.4 59325.4 60304.0 60484.0 60591.4 61076.0 86071.0 87229.0 87320.0 89297.6 90153.5 13016.4 13176.3 13218.9 13364.7 13654.7 14430.6 14491.6 14893.6 14970.8 15616.4 12759.1 12797.4 13197.5 13262.7 13319.5 13058.9 13543.7 13562.0 13831.9 14120.2 12430.0 12491.6 12649.2 12763.2 13454.0 10378.5 10516.5 10595.4 10723.9 11016.0 10003.4 10829.9 10840.9 10847.1 10851.7 14:54:21 INFO - PROCESS | 21893 | -------- Summary: end -------- 14:54:21 INFO - PROCESS | 21893 | 14:54:22 INFO - TEST-INFO | 21893: exit 0 14:54:22 INFO - TEST-OK | dromaeo_css | took 1112876ms 14:54:22 INFO - TEST-START | kraken 14:54:22 INFO - operating with platform_type : linux_ 14:54:22 INFO - Initialising browser for kraken test... 14:54:22 INFO - TEST-INFO | started process 22097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi1kgob/profile http://localhost:55336/getInfo.html) 14:54:26 INFO - PROCESS | 22097 | __metrics Screen width/height:1600/1200 14:54:26 INFO - PROCESS | 22097 | colorDepth:24 14:54:26 INFO - PROCESS | 22097 | Browser inner width/height: 1024/697 14:54:26 INFO - PROCESS | 22097 | __metrics 14:54:26 INFO - PROCESS | 22097 | 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" 14:54:27 INFO - PROCESS | 22097 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:54:27 INFO - TEST-INFO | 22097: exit 0 14:54:27 INFO - Browser initialized. 14:54:27 INFO - Running cycle 1/1 for kraken test... 14:54:27 INFO - TEST-INFO | started process 22186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi1kgob/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:54:39 INFO - PROCESS | 22186 | RSS: Main: 145227776 14:54:39 INFO - PROCESS | 22186 | 14:54:42 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55336/tests/kraken/driver.html?testName=audio-beat-detection) 14:54:42 INFO - PROCESS | 22186 | RSS: Main: 191922176 14:54:42 INFO - PROCESS | 22186 | 14:54:45 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55336/tests/kraken/driver.html?testName=audio-dft) 14:54:46 INFO - PROCESS | 22186 | RSS: Main: 181829632 14:54:46 INFO - PROCESS | 22186 | 14:54:48 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55336/tests/kraken/driver.html?testName=audio-fft) 14:54:49 INFO - PROCESS | 22186 | RSS: Main: 269512704 14:54:49 INFO - PROCESS | 22186 | 14:54:51 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55336/tests/kraken/driver.html?testName=audio-oscillator) 14:54:52 INFO - PROCESS | 22186 | RSS: Main: 175710208 14:54:52 INFO - PROCESS | 22186 | 14:54:54 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55336/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:54:54 INFO - PROCESS | 22186 | RSS: Main: 176697344 14:54:54 INFO - PROCESS | 22186 | 14:54:57 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55336/tests/kraken/driver.html?testName=imaging-darkroom) 14:54:58 INFO - PROCESS | 22186 | RSS: Main: 213454848 14:54:58 INFO - PROCESS | 22186 | 14:55:01 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55336/tests/kraken/driver.html?testName=imaging-desaturate) 14:55:02 INFO - PROCESS | 22186 | RSS: Main: 211726336 14:55:02 INFO - PROCESS | 22186 | 14:55:05 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55336/tests/kraken/driver.html?testName=json-parse-financial) 14:55:05 INFO - PROCESS | 22186 | RSS: Main: 211857408 14:55:05 INFO - PROCESS | 22186 | 14:55:07 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55336/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:55:07 INFO - PROCESS | 22186 | RSS: Main: 175874048 14:55:07 INFO - PROCESS | 22186 | 14:55:09 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:55:10 INFO - PROCESS | 22186 | RSS: Main: 181714944 14:55:10 INFO - PROCESS | 22186 | 14:55:12 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:55:12 INFO - PROCESS | 22186 | RSS: Main: 186208256 14:55:12 INFO - PROCESS | 22186 | 14:55:15 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:55:15 INFO - PROCESS | 22186 | RSS: Main: 180899840 14:55:15 INFO - PROCESS | 22186 | 14:55:18 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:55:19 INFO - PROCESS | 22186 | RSS: Main: 184487936 14:55:19 INFO - PROCESS | 22186 | 14:55:20 INFO - PROCESS | 22186 | Cycle 1(1): loaded http://localhost:55336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55336/tests/kraken/driver.html?testName=ai-astar) 14:55:21 INFO - PROCESS | 22186 | RSS: Main: 235552768 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | __start_tp_report 14:55:21 INFO - PROCESS | 22186 | _x_x_mozilla_page_load 14:55:21 INFO - PROCESS | 22186 | _x_x_mozilla_page_load_details 14:55:21 INFO - PROCESS | 22186 | |i|pagename|runs| 14:55:21 INFO - PROCESS | 22186 | |0;ai-astar;99;110;110;108;111;110;108;109;111;110 14:55:21 INFO - PROCESS | 22186 | |1;audio-beat-detection;140;116;120;172;116;117;138;119;145;116 14:55:21 INFO - PROCESS | 22186 | |2;audio-dft;161;160;160;160;161;161;160;160;160;160 14:55:21 INFO - PROCESS | 22186 | |3;audio-fft;77;75;74;110;75;75;85;74;98;74 14:55:21 INFO - PROCESS | 22186 | |4;audio-oscillator;83;83;135;83;83;84;116;83;84;135 14:55:21 INFO - PROCESS | 22186 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;116;116;116 14:55:21 INFO - PROCESS | 22186 | |6;imaging-darkroom;145;146;146;146;148;146;146;147;147;145 14:55:21 INFO - PROCESS | 22186 | |7;imaging-desaturate;96;94;95;94;95;94;95;95;95;95 14:55:21 INFO - PROCESS | 22186 | |8;json-parse-financial;52;50;51;51;50;52;51;52;50;50 14:55:21 INFO - PROCESS | 22186 | |9;json-stringify-tinderbox;59;60;60;60;61;60;60;60;60;61 14:55:21 INFO - PROCESS | 22186 | |10;stanford-crypto-aes;73;72;73;73;77;73;72;82;77;71 14:55:21 INFO - PROCESS | 22186 | |11;stanford-crypto-ccm;118;119;124;124;120;116;117;118;157;127 14:55:21 INFO - PROCESS | 22186 | |12;stanford-crypto-pbkdf2;151;150;151;152;152;151;149;152;155;152 14:55:21 INFO - PROCESS | 22186 | |13;stanford-crypto-sha256-iterative;61;65;62;61;63;61;61;62;61;61 14:55:21 INFO - PROCESS | 22186 | __end_tp_report 14:55:21 INFO - PROCESS | 22186 | __start_cc_report 14:55:21 INFO - PROCESS | 22186 | _x_x_mozilla_cycle_collect,1669 14:55:21 INFO - PROCESS | 22186 | __end_cc_report 14:55:21 INFO - PROCESS | 22186 | __startTimestamp1471902921050__endTimestamp 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | ------- Summary: start ------- 14:55:21 INFO - PROCESS | 22186 | Number of tests: 14 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:3.53 (3.2%) stddev-sans-first:1.12 14:55:21 INFO - PROCESS | 22186 | Values: 99.0 110.0 110.0 108.0 111.0 110.0 108.0 109.0 111.0 110.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:129.00 stddev:18.65 (14.5%) stddev-sans-first:19.42 14:55:21 INFO - PROCESS | 22186 | Values: 140.0 116.0 120.0 172.0 116.0 117.0 138.0 119.0 145.0 116.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 14:55:21 INFO - PROCESS | 22186 | Values: 161.0 160.0 160.0 160.0 161.0 161.0 160.0 160.0 160.0 160.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:12.53 (16.7%) stddev-sans-first:13.17 14:55:21 INFO - PROCESS | 22186 | Values: 77.0 75.0 74.0 110.0 75.0 75.0 85.0 74.0 98.0 74.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:83.00 stddev:22.53 (27.1%) stddev-sans-first:23.32 14:55:21 INFO - PROCESS | 22186 | Values: 83.0 83.0 135.0 83.0 83.0 84.0 116.0 83.0 84.0 135.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 14:55:21 INFO - PROCESS | 22186 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 14:55:21 INFO - PROCESS | 22186 | Values: 145.0 146.0 146.0 146.0 148.0 146.0 146.0 147.0 147.0 145.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 14:55:21 INFO - PROCESS | 22186 | Values: 96.0 94.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:0.88 (1.7%) stddev-sans-first:0.83 14:55:21 INFO - PROCESS | 22186 | Values: 52.0 50.0 51.0 51.0 50.0 52.0 51.0 52.0 50.0 50.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 14:55:21 INFO - PROCESS | 22186 | Values: 59.0 60.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.00 stddev:3.37 (4.6%) stddev-sans-first:3.54 14:55:21 INFO - PROCESS | 22186 | Values: 73.0 72.0 73.0 73.0 77.0 73.0 72.0 82.0 77.0 71.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:122.00 stddev:12.13 (9.9%) stddev-sans-first:12.67 14:55:21 INFO - PROCESS | 22186 | Values: 118.0 119.0 124.0 124.0 120.0 116.0 117.0 118.0 157.0 127.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.50 Median:152.00 stddev:1.58 (1.0%) stddev-sans-first:1.67 14:55:21 INFO - PROCESS | 22186 | Values: 151.0 150.0 151.0 152.0 152.0 151.0 149.0 152.0 155.0 152.0 14:55:21 INFO - PROCESS | 22186 | 14:55:21 INFO - PROCESS | 22186 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:1.32 (2.1%) stddev-sans-first:1.36 14:55:21 INFO - PROCESS | 22186 | Values: 61.0 65.0 62.0 61.0 63.0 61.0 61.0 62.0 61.0 61.0 14:55:21 INFO - PROCESS | 22186 | -------- Summary: end -------- 14:55:21 INFO - PROCESS | 22186 | 14:55:22 INFO - TEST-INFO | 22186: exit 0 14:55:22 INFO - TEST-OK | kraken | took 59834ms 14:55:22 INFO - SUITE-END | took 1172s 14:55:22 INFO - Completed test suite (00:19:32) 14:55:22 INFO - javascript benchmark 14:55:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3219.3883162199186, 3239.3683231769814, 3251.837408129593, 3266.1018219989487, 3287.3753986742518, 5597.944020559793, 5656.886862262752, 5684.516816070639, 5689.800856969997, 5695.373508914016, 5651.943480565194, 5722.513586345155, 5733.254676891998, 5748.080307150846, 5766.9711651441785, 4903.583195428375, 4943.480934501879, 5191.247269145972, 5197.428282888879, 5223.294855194557, 5068.518490743373, 5096.770645320969, 5098.413682426512, 5101.234814777795, 5107.923381149286, 5017.673851199678, 5073.441921388644, 5078.035173317058, 5085.0, 5100.566451851597, 5135.947130838169, 5158.92261616076, 5160.329157209558, 5161.66449180802, 5181.533661970422, 4966.801327946878, 5049.596032317406, 5066.0, 5091.643584949055, 5118.462561431071, 5512.862178445531, 5526.50261476469, 5529.723513824292, 5632.07070833312, 5635.323761148688, 6062.927496580028, 6089.969550152221, 6097.634141951497, 6101.450869421729, 6102.72537735803, 5909.231799866033, 5964.075568286922, 5987.700614969234, 6082.5742198046155, 6119.694015299262, 5626.887462250732, 5661.320641523002, 5669.8299051028125, 5675.404082571353, 5675.772969081273, 5964.224650795369, 6133.570650054543, 6140.854922927007, 6147.538934579924, 6148.815535533941, 5933.792317268875, 5960.225170727865, 5974.0, 6073.271207455045, 6090.908636370458, 6124.425803874611, 6132.63204207749, 6134.938650613437, 6143.232095988002, 6145.139680444741, 5763.423657634203, 6117.398686497024, 6119.562077244144, 6134.436587033385, 6134.987697083746, 5998.130271110665, 6053.818385448443, 6057.7879774207695, 6116.082587611894, 6123.326434092246, 4674.555917187861, 4677.22825733757, 4679.087577922341, 4679.3916790816975, 4706.599939005155, 4055.351143817034, 4139.544650088488, 4175.206710724952, 4185.665146788249, 4197.3284274516545, 5662.886742265132, 5716.114002329646, 5723.685197314105, 5765.250517432707, 5777.2200752897825, 6104.9109929343795, 6110.847228819226, 6110.969445152835, 6111.419415155553, 6117.480014198754, 5673.517750991129, 5694.971525142348, 5732.168835518826, 5744.655320680772, 5748.540116790647, 4939.0, 5005.449400565934, 5086.262491938722, 5122.795088196505, 5198.220266959911, 6337.2395312562785, 6374.266959299647, 6375.553711240169, 6376.362363763679, 6377.3303803100935, 6075.635461872302, 6107.84730381744, 6129.724162412613, 6130.325664176848, 6143.938560614337, 6074.36219196978, 6089.451949324582, 6095.05526643371, 6110.969445152746, 6206.348333424925, 5903.498202652822, 5907.143464197581, 5912.379200184091, 5917.0, 5951.256092988377, 6017.909731353946, 6018.037114061729, 6027.126066720389, 6033.0, 6034.517238620988, 5746.0, 5832.883342333214, 5872.0, 5882.764689412375, 5964.761409543743, 5927.051671732502, 5932.0508718604815, 5934.0, 5934.762609495572, 5937.7921772737745, 5828.329742079715, 5844.620099693507, 5876.911846322223, 5879.912216240009, 5897.498712609477, 5792.160136780228, 5880.882382352414, 5949.0, 5951.434613711691, 6030.6080104793045, 5841.299044114563, 5858.443447872445, 5864.296284445893, 5867.149263356875, 5868.061110222344], "value": 5569.917778067234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.8853212155749, 626.2516293029828, 637.5314144104083, 649.8927676933306, 663.767681311541, 35123.47314790277, 46484.0, 47864.76067619666, 47869.76065119678, 51569.48430515694, 49540.50459495413, 49912.50087499124, 50026.49973500264, 50067.74966125164, 50093.24860127101, 8257.628406721682, 9167.862482062757, 9313.90686093137, 9325.487098209596, 10929.344239345664, 10721.463926803668, 11170.106391488662, 11175.944120279388, 11213.831792523119, 11215.327080375204, 11189.384583847885, 11278.943605282004, 11289.040431563286, 11292.322460652387, 11293.661190164308, 8378.162183781635, 10854.45727713619, 11210.831837522444, 11244.100471962223, 11279.605213817518, 11083.334999900031, 11171.050460710769, 11175.161862860237, 11177.049950754195, 11180.04969577587, 45971.310430343234, 46373.0, 46375.30437043447, 46379.5362046382, 46383.0, 3679.8528058877614, 3763.7741735495683, 3887.9222415551812, 4024.1549274652357, 4319.308910574325, 35064.0, 35310.16557375441, 35403.64596354029, 35497.0, 36041.819790900874, 4665.6967297125575, 4675.4155730533685, 4701.788419521095, 4711.3639658646325, 4743.398504342791, 394711.0, 396064.0, 396583.0, 399484.0, 399564.0, 63501.0, 63543.6822815883, 63769.68115159487, 64102.67948660227, 64197.67901160464, 39393.0, 40060.79969600133, 40257.0, 42564.36153457701, 67583.0, 19872.0, 20161.294354697515, 20175.0, 20175.092120854595, 20175.39473815788, 13934.93032534851, 14350.856491434952, 14364.784528231876, 14706.852931470763, 15237.314320855588, 20531.4867128323, 21289.78710212878, 21307.68038479424, 22196.22313219062, 22305.44236394103, 7637.350825179811, 7723.073231212291, 7801.9609901950125, 7830.530168189927, 7964.800879978047, 1019.7960407918298, 1048.4600430778155, 1104.1718710966775, 1158.391844281742, 1229.6329565586211, 31523.527147092813, 34074.31851362959, 34671.479927801105, 34754.30491390158, 34989.82505087509, 1185.9051275897907, 1195.8863907928908, 1225.4485481533345, 1235.7837378458735, 1291.9935400323127, 17965.371212007518, 17992.730109048374, 18026.188821503063, 18037.639247215244, 18301.7254741179, 623172.0, 623390.0, 628850.0, 630204.0, 631466.0, 65713.34286657072, 66325.33674663288, 66368.33631663621, 66467.33532664612, 66633.33366666271, 1363.8908887288992, 1445.2918070145763, 1501.542029680936, 1521.0341433189783, 1521.9239038048054, 34307.0, 35812.03971495928, 36517.81741091277, 37232.25535489221, 38326.0, 64488.67755661192, 65210.67394662996, 65261.0, 65339.673301633185, 65478.67260663666, 544486.0, 545217.0, 546931.0, 549661.0, 551414.0, 45346.31980520361, 49003.75498122487, 49215.50784492109, 49302.0, 49463.0, 54981.72509137429, 55074.0, 55158.0, 55211.723941380034, 55955.72022139863, 44733.329000064376, 46501.76749116368, 47272.7636361816, 47753.761231193625, 48143.0, 45943.31085033794, 46395.30407043965, 46502.76748616235, 46579.767101165635, 47619.523804761506, 46168.0, 46373.07253854971, 46530.767346163055, 46624.53375466202, 46831.76584117058, 36927.630723693495, 40701.0, 41251.38122928098, 41753.58246417619, 41958.580414195465, 30928.845355773075, 31081.844590776902, 31105.8444707775, 31235.0, 31612.209694757814], "value": 22833.854405790404, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.0726532607665, 408.36906978717894, 423.76057527496954, 426.99359509607353, 440.4384409877408, 15356.539303820875, 20001.599968000668, 20535.0, 20648.793512064894, 20789.896050519746, 19566.90216548915, 19572.70640940387, 19632.116554755034, 19650.50873728154, 19862.40412787615, 5855.824325270228, 5899.675517846516, 5900.203472531218, 5996.190514280582, 6014.1580178775, 5972.611780234292, 6016.157737916695, 6101.023836186211, 6112.908306375408, 6114.69426528674, 5815.098659707752, 5957.0, 6013.548983826208, 6048.909266361008, 6090.269167699882, 5911.438413350767, 5960.970195148997, 5974.731137098841, 6003.789867354665, 6021.9698901505435, 5225.6603320784025, 5355.250264962909, 5355.973220133913, 5357.116075847481, 5360.544353729938, 16450.177491125396, 21003.894980525154, 21687.45781355463, 21775.891120544296, 23361.065557377686, 2372.3238876919995, 2467.864267465289, 2575.9098431554808, 2584.5735453650127, 3099.7210251077513, 14931.25343732809, 14962.17708026058, 15641.217939102999, 15667.373305067784, 15733.213339332988, 2056.609244243589, 2065.132644289387, 2077.4390914453165, 2082.1775398717573, 2132.72274604302, 80686.98282542983, 87567.56216218878, 89337.0, 89680.55159724224, 90239.54880225622, 11685.415729213506, 11752.706182345339, 11875.70310742221, 11900.107491938139, 12056.87943120575, 16744.665106697797, 16757.0, 16760.832391675925, 16781.748273775902, 16789.076600787073, 7869.2130786922, 8300.626471808782, 8771.298296136294, 8841.734747957571, 8898.243649289883, 8553.871691924625, 8724.520151391607, 8832.602532886036, 8908.688195913111, 8913.688020919237, 5003.224500202475, 5009.624278179152, 5027.597792176617, 5032.521910418504, 5115.488451154929, 2054.979450205509, 2080.9479763006048, 2094.214669498938, 2101.737282839645, 2147.194801949269, 945.8912225094065, 991.2218908157062, 1029.6807989523122, 1071.137734124022, 1112.1714322829544, 19913.900430497753, 20442.488937776674, 20546.0, 20630.793692063187, 20690.896545517477, 1051.4344998204356, 1066.7599790047177, 1071.9732006699894, 1073.75303680154, 1079.3254216114929, 10197.490125493696, 11377.715557110972, 11395.14536409756, 11432.485538150802, 11455.427228638535, 570010.0, 570815.0, 572206.0, 574792.0, 590590.0, 91000.0, 95378.0, 96720.54919176218, 99056.50471747595, 100720.0, 988.4761076629397, 1020.2297265564451, 1026.6714651311509, 1037.0821822686976, 1039.0804138337473, 17127.143642818057, 17846.91076544609, 18159.727604085685, 18309.084545772923, 18376.90811545987, 40995.0, 42026.0, 42233.36649950314, 43187.0, 43651.56348436475, 53681.73159134179, 60207.69896150491, 60552.39447605467, 61377.38622613895, 62737.0, 8085.272325490734, 8412.747617571482, 8509.0, 8980.910190898006, 9244.768880778034, 10293.74265643365, 13904.165750055028, 13998.37007334652, 14438.0, 14527.27363631793, 11661.883381166079, 11924.701882453008, 12188.57339993096, 12256.325902075472, 12455.875441245471, 10861.013221364188, 12007.339596322287, 12057.638270851889, 12139.817902731289, 12232.755344892874, 11874.703132421759, 12026.692659518701, 12201.633950981484, 12234.87765122373, 12601.873981260436, 7941.166177551274, 8133.14601966785, 8160.0, 8391.832163356577, 8544.40189186751, 9749.951250243703, 11867.703307417383, 11987.40062996864, 12240.816387754368, 12299.93850030744], "value": 9703.59977371906, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.637840539865, 1535.1710076558659, 1604.5747876812648, 1760.2606905099856, 1875.7655293088364, 8150.673973041079, 9193.540322983858, 9614.0, 9630.13328800407, 9751.70744877653, 9419.152276295132, 9460.526973651324, 9467.715968520939, 9470.66852660157, 9488.66789662362, 2467.346153269385, 2513.070164039299, 2513.3465299022296, 2516.7860731837814, 2520.3573088862313, 2355.305184970438, 2432.74456182101, 2456.533258680854, 2462.372095115743, 2467.4201562632857, 2422.5033868057008, 2437.987810060947, 2465.4822487277693, 2475.3687809608614, 2486.29140694902, 2354.5526349993534, 2378.8929498172624, 2384.5469360821476, 2427.9028838846425, 2431.489387228684, 2377.679013333195, 2387.8089752819733, 2387.820913431482, 2388.1880160745254, 2389.7012873390827, 9373.484458354787, 9532.428054316762, 9546.0, 9572.282078844044, 9579.952100239454, 5688.886222275572, 5909.527237820964, 5922.763089476416, 5925.844460330238, 5946.197263369433, 3499.860005599773, 3500.702440292578, 3538.380783362927, 3556.270964452281, 3564.6276183669215, 1211.7818792617413, 1219.0613227814622, 1225.8038713805838, 1256.2462522486526, 1258.7104965857811, 13744.793828092586, 13939.860601393957, 13940.372683229178, 14019.439222431192, 14032.08791428554, 4918.9508104919205, 4931.753412329369, 4944.629152813553, 4984.576311013532, 5167.199084141964, 10532.947335263274, 10540.209484288709, 10551.0, 10558.577656893638, 10565.418901960466, 5668.943310566841, 5679.403662615448, 5700.6009579329875, 5715.199872017921, 5717.028105222123, 4731.668783185144, 4753.073150735573, 5253.264542963988, 5305.389880163831, 5326.2543243945875, 4267.935980960288, 4299.828006879752, 4308.5045219799495, 4351.238533256667, 4471.709338893027, 1282.6344491819787, 1324.218710960538, 1329.9601011969658, 1332.360466975857, 1347.3061373392711, 989.8564708117283, 1064.8296272596347, 1094.950727217277, 1095.3209010413595, 1101.691526372601, 6616.106825578581, 6658.43403310724, 6692.899606505906, 6865.828354291183, 6867.381935625817, 510.81610620176707, 515.2245869965752, 519.4441947116625, 524.7376311844077, 526.6471464119049, 4662.7202367858035, 4677.251639737626, 4691.225947718656, 4734.21885388907, 4816.349792778, 11541.0, 11548.076153907667, 12013.459394327276, 12032.578859740042, 12078.879211207952, 11665.76668466626, 11901.226420282826, 11921.0, 11936.761264774656, 11938.403079845973, 8884.777880553038, 8925.285977121815, 8943.926728792456, 8994.550272486349, 9048.230900373543, 6513.902291465537, 6583.34166583357, 6642.535022548375, 6644.468442524683, 6664.400203981665, 10771.19216058783, 10818.783624327627, 10994.395308258123, 11106.222564420414, 11140.944295278796, 9988.200943924323, 10702.357858528514, 11135.053520450852, 11188.88811111911, 11271.041961433042, 7846.215378462336, 8048.315893149148, 8062.677492900219, 8091.352691784526, 8101.635426405944, 9028.683996060106, 9051.185393314634, 9098.863517047383, 9101.317401194805, 9104.18062374363, 8182.4681395709085, 8409.831803363775, 8505.489670619783, 8534.829303413773, 8658.0, 7999.720009799629, 8110.351171906115, 8201.630926608435, 8279.62741676638, 8618.525981071101, 7273.3817625500305, 7393.408527317909, 7519.736809211651, 7609.124950630527, 7610.847783044419, 5583.776648934159, 5625.268715067015, 5787.5369970401425, 5789.56578256624, 5896.55775816824, 5885.941140588539, 5894.6757928314355, 5916.881662366814, 5954.0, 6145.754169833157], "value": 4923.990732593832, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.4258301551487, 359.3604971484447, 363.51833820188256, 366.15418383534023, 366.90827293176704, 5458.0, 5580.3816893101, 5595.160725891118, 5599.1041433370665, 5622.859428514289, 5441.2654291670615, 5451.318585176853, 5481.177193745224, 5490.972545137269, 5507.504324610784, 4154.418381426595, 4306.418633484486, 4308.676849236304, 4332.350147477872, 4353.62994145498, 4269.316909294514, 4290.635295999844, 4315.180115778008, 4331.852059204298, 4350.956490435086, 4312.353147027955, 4324.654027677794, 4331.0255192581735, 4332.4151239582725, 4334.804933777972, 4167.854125105622, 4284.564670835242, 4290.163418133465, 4312.5903039211225, 4330.6968512204, 3803.5055442792536, 3810.2760475509563, 3815.6184381561898, 3816.1985982943615, 3827.789471579062, 5390.460953904618, 5421.457854214586, 5426.565874730012, 5444.40111587725, 5466.617336786427, 4683.250679891201, 4888.364512613373, 4942.604591632697, 4944.678595891256, 4983.302337672729, 5597.552195824324, 5710.8001219957105, 5715.971420142914, 5717.142428635696, 5720.828375148711, 4128.938065929014, 4137.8758637240935, 4142.026623743418, 4161.189882401135, 4183.602557757008, 5852.941470585325, 5883.441073098049, 5885.0, 5888.97055514724, 6014.8195554133445, 4256.425382573375, 4261.936070958938, 4264.829406823692, 4275.957240427618, 4287.442632457761, 4324.610785029363, 4326.24290749124, 4328.43730315057, 4329.242382583098, 4335.1329734053315, 4757.643176761757, 4857.53853383928, 4865.829695960695, 4896.5837903777865, 4915.3855768029, 5690.9146362804595, 5703.287089113861, 5707.172459993278, 5708.914366284509, 5713.200151978726, 1454.789055586934, 1485.1311982490365, 1487.0, 1489.5010171592637, 1494.9103053816805, 925.9166674999283, 973.352720440913, 973.5375696544227, 974.4640447753849, 982.9826129955604, 4880.389951256093, 4909.2881532177635, 4914.754262286943, 4942.703437793743, 4979.551840334388, 5104.744762761847, 5125.89793194465, 5147.3308469898675, 5173.689578625294, 5212.218167274939, 4649.697769644962, 4939.629527785359, 4945.8021679132435, 4978.726170060681, 4980.676256043345, 2715.782737381044, 2739.4794988952035, 2765.128984369917, 2766.7509924106926, 2767.4741799058115, 5284.4187139414635, 5309.65487243328, 5311.336082989626, 5346.251524786533, 5351.795845934634, 5281.973590132102, 5297.973510132425, 5586.55307575393, 5673.716314184274, 5928.555358348141, 5890.2342695449315, 6003.5797494174985, 6068.180795592539, 6109.786157484378, 6156.722947467375, 5881.323822710507, 5890.43903365617, 5893.469587736953, 5995.790147344822, 6001.5798894077, 5602.663840169603, 5719.8570035749435, 5844.6785426801935, 5869.8239052828485, 5887.4406931341455, 6065.272167339947, 6107.144999700135, 6164.537659675452, 6172.783952561818, 6173.629582225081, 4175.749455032707, 4331.848385306499, 4368.716033457815, 4384.430024096847, 4393.29707246839, 5854.443827836349, 5858.7070646468355, 5868.503531599415, 5877.882442351043, 5889.381614930542, 5680.9548562518285, 5718.714064296685, 5725.307176870978, 5749.0, 5782.45066718661, 5647.20939068539, 5701.173329867229, 5723.255976723, 5733.197352370755, 5789.623674461147, 5632.662040277596, 5637.830865074055, 5641.605087643826, 5687.260656114679, 5690.886182276414, 5634.830955071355, 5711.257536520226, 5770.2498675171955, 5790.552361909523, 5807.0, 5592.5525957922455, 5606.943930560642, 5608.523275521627, 5637.915431268616, 5644.294463192101], "value": 4322.36041863141, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.009617005245, 810.2990912860374, 848.2832006954123, 856.1267507142715, 872.4198408058642, 15978.68042639148, 17421.912890435546, 17430.128493575303, 17449.476515704533, 17467.039312837787, 15581.922090389533, 15784.289706963185, 15795.131267780267, 15809.288582013809, 15823.920880395583, 714.8427345983876, 735.9668814903315, 739.2718172599984, 741.2735519191195, 843.5191940593865, 877.5787621941472, 890.3900827932887, 897.8787863638423, 899.8605216191468, 900.3877363392891, 930.6928713524554, 937.8686983822271, 941.0119374656617, 941.2611100286276, 943.1511639524414, 755.4749449132855, 941.1858742187992, 941.2140862379887, 941.29873244433, 941.4868896451451, 659.4888961055173, 660.1780782925241, 665.7037618259876, 680.6664734280216, 690.2545251128769, 15366.154861482613, 15397.692046159014, 15428.3828646854, 15431.459898903597, 15663.608409789733, 14968.251587420586, 15573.454929077428, 15635.530934071996, 15703.0, 15773.684526309524, 14472.565823025327, 14487.71024579513, 14492.782608260884, 14517.491887783983, 14783.630409239748, 4676.18166820805, 4698.671093023489, 4700.764961751899, 4789.832355867563, 4803.423589169322, 87209.12790872074, 87805.0, 88691.0, 93716.0, 94512.52743736307, 11054.0, 11059.281146725438, 11124.33254004746, 11285.492152853141, 11446.771064578661, 21853.453663658216, 21976.560468790536, 22079.0, 22123.446913827283, 22210.444738881335, 8912.777180570409, 9039.23166530839, 9239.76900577491, 9247.953760231156, 9381.577828997712, 5007.24891266313, 5089.720065396365, 5126.154184559506, 5132.820351287687, 5246.370435547682, 3714.0529165062735, 4180.811863466151, 4296.398504209413, 4326.048269380732, 4347.456567929009, 690.4959379652781, 700.3766647683566, 700.8458139209366, 704.6828926982776, 706.5372181221309, 690.268315585481, 736.7421402509186, 769.5190505933791, 798.4371018431991, 822.42841225349, 9789.853152202722, 9841.901580984242, 9958.502074896227, 10281.948590257001, 10323.32898361604, 783.9960800195962, 807.9838403231903, 808.5229714468492, 824.3034635732796, 846.703653721205, 4534.682572219979, 4674.57928786404, 4733.148033354029, 4795.040991801654, 4806.014766972832, 104263.43604845933, 128420.0, 129967.0, 137542.0, 140871.0, 115882.0, 117203.41398292955, 119736.0, 120164.0, 122599.0, 103838.48080759548, 104872.0, 105834.0, 106015.0, 107823.0, 8589.914100859161, 8776.16626420489, 8822.5588720565, 8861.822763544822, 8967.389955371662, 59325.40674593372, 60304.0, 60484.0, 60591.39408605857, 61076.0, 86071.0, 87229.0, 87320.0, 89297.55351223203, 90153.54923225341, 13016.414261358452, 13176.341182941007, 13218.867811321763, 13364.732705346034, 13654.658633534242, 14430.639234019234, 14491.637709057359, 14893.574676842836, 14970.775438368652, 15616.375344986074, 12759.106862519535, 12797.360131993177, 13197.538086166938, 13262.734745305233, 13319.467221311039, 13058.934705326412, 13543.729125417634, 13562.0, 13831.930840345734, 14120.223387713364, 12430.0, 12491.562795302121, 12649.177803442746, 12763.170393924365, 13454.0, 10378.481075946323, 10516.491907511456, 10595.364278143335, 10723.94638026805, 11016.0, 10003.399796012263, 10829.945850270698, 10840.891591084304, 10847.13222942147, 10851.728706782395], "value": 8098.354468496345, "unit": "score"}], "name": "dromaeo_css", "value": 7726.339295260531, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 110.0, 108.0, 111.0, 110.0, 108.0, 109.0, 111.0, 110.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 120.0, 172.0, 116.0, 117.0, 138.0, 119.0, 145.0, 116.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 160.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 74.0, 110.0, 75.0, 75.0, 85.0, 74.0, 98.0, 74.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 135.0, 83.0, 83.0, 84.0, 116.0, 83.0, 84.0, 135.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 148.0, 146.0, 146.0, 147.0, 147.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 51.0, 50.0, 52.0, 51.0, 52.0, 50.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 73.0, 77.0, 73.0, 72.0, 82.0, 77.0, 71.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 124.0, 124.0, 120.0, 116.0, 117.0, 118.0, 157.0, 127.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 152.0, 152.0, 151.0, 149.0, 152.0, 155.0, 152.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 62.0, 61.0, 63.0, 61.0, 61.0, 62.0, 61.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1457.4999999999998, "alertThreshold": 2.0}]} 14:55:22 INFO - Return code: 0 14:55:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:55:22 INFO - # TBPL SUCCESS # 14:55:22 INFO - Running post-action listener: _resource_record_post_action 14:55:22 INFO - [mozharness: 2016-08-22 21:55:22.377596Z] Finished run-tests step (success) 14:55:22 INFO - Running post-run listener: _resource_record_post_run 14:55:22 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 315392; Write bytes: 328679424; Read time: 260; Write time: 187928 14:55:22 INFO - TinderboxPrint: CPU usage
12.9% 14:55:22 INFO - TinderboxPrint: I/O read bytes / time
315,392 / 260 14:55:22 INFO - TinderboxPrint: I/O write bytes / time
328,679,424 / 187,928 14:55:22 INFO - TinderboxPrint: CPU idle
8,260.2 (87.0%) 14:55:22 INFO - TinderboxPrint: CPU user
1,184.0 (12.5%) 14:55:22 INFO - TinderboxPrint: Swap in / out
0 / 0 14:55:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77062144; Read time: 0; Write time: 43164 14:55:22 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 311296; Write bytes: 249208832; Read time: 232; Write time: 144560 14:55:23 INFO - Running post-run listener: _upload_blobber_files 14:55:23 INFO - Blob upload gear active. 14:55:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:55:23 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 14:55:23 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'] 14:55:23 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 14:55:23 INFO - (blobuploader) - INFO - Open directory for files ... 14:55:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:55:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:55:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:55:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:55:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:55:24 INFO - (blobuploader) - INFO - Done attempting. 14:55:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:55:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:55:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:55:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:55:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:55:27 INFO - (blobuploader) - INFO - Done attempting. 14:55:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:55:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:55:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:55:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:55:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:55:28 INFO - (blobuploader) - INFO - Done attempting. 14:55:28 INFO - (blobuploader) - INFO - Iteration through files over. 14:55:28 INFO - Return code: 0 14:55:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:55:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:55:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d1b913d018edf6074938cbb359ae3654a1b33066ac14e504a0de78d50e4241282055f135ab693fade70e2f6ba9d56541aaa389847c572ef0d8596471c92c304", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0675d1d6b542b2bf2ec86b2a695bcc930de0b33fff4d870eb92202e62380aef0977bbacc7836140d24959368a9478c931126d20f731929b4ab88643f0d46458a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed70dd92dbada3810a422445283f4a1cf781c0e7fb53ddebaaadc924e1ee7c56086fce793579cb26153f6a6f6907fd5643b22e23dd34f0df384e16d238ee9938"} 14:55:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:55:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:55:28 INFO - Contents: 14:55:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d1b913d018edf6074938cbb359ae3654a1b33066ac14e504a0de78d50e4241282055f135ab693fade70e2f6ba9d56541aaa389847c572ef0d8596471c92c304", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0675d1d6b542b2bf2ec86b2a695bcc930de0b33fff4d870eb92202e62380aef0977bbacc7836140d24959368a9478c931126d20f731929b4ab88643f0d46458a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed70dd92dbada3810a422445283f4a1cf781c0e7fb53ddebaaadc924e1ee7c56086fce793579cb26153f6a6f6907fd5643b22e23dd34f0df384e16d238ee9938"} 14:55:28 INFO - Running post-run listener: copy_logs_to_upload_dir 14:55:28 INFO - Copying logs to upload dir... 14:55:28 INFO - mkdir: /builds/slave/test/build/upload/logs 14:55:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.865613 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-22 14:55:27.535249) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:55:27.538449) ========= 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=232498196343f89f466a4a9a000001c8-1471901249.97257-45535204 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/9d1b913d018edf6074938cbb359ae3654a1b33066ac14e504a0de78d50e4241282055f135ab693fade70e2f6ba9d56541aaa389847c572ef0d8596471c92c304", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0675d1d6b542b2bf2ec86b2a695bcc930de0b33fff4d870eb92202e62380aef0977bbacc7836140d24959368a9478c931126d20f731929b4ab88643f0d46458a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed70dd92dbada3810a422445283f4a1cf781c0e7fb53ddebaaadc924e1ee7c56086fce793579cb26153f6a6f6907fd5643b22e23dd34f0df384e16d238ee9938"} build_url:https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011327 build_url: 'https://queue.taskcluster.net/v1/task/U2T52vHUQmiw4ELmJQDxdg/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/9d1b913d018edf6074938cbb359ae3654a1b33066ac14e504a0de78d50e4241282055f135ab693fade70e2f6ba9d56541aaa389847c572ef0d8596471c92c304", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0675d1d6b542b2bf2ec86b2a695bcc930de0b33fff4d870eb92202e62380aef0977bbacc7836140d24959368a9478c931126d20f731929b4ab88643f0d46458a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed70dd92dbada3810a422445283f4a1cf781c0e7fb53ddebaaadc924e1ee7c56086fce793579cb26153f6a6f6907fd5643b22e23dd34f0df384e16d238ee9938"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:55:27.564532) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:55:27.564850) ========= 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=232498196343f89f466a4a9a000001c8-1471901249.97257-45535204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:55:27.648038) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:55:27.648439) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:55:27.648771) ========= ========= Total master_lag: 0.15 =========