builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1465103839.59 results: success (0) buildid: 20160604190644 builduid: 8c8cb62ca25f4a55bba56c381457195b revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.591333) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.591952) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.592450) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.668834) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.669252) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-04 22:17:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 112M=0s 2016-06-04 22:17:20 (112 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.799821) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.800114) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019753 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.850924) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:19.851229) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc972d877cd697c2c8f6248534f21bb5e1dba9ae --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 dc972d877cd697c2c8f6248534f21bb5e1dba9ae --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=e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-04 22:17:20,557 truncating revision to first 12 chars 2016-06-04 22:17:20,557 Setting DEBUG logging. 2016-06-04 22:17:20,557 attempt 1/10 2016-06-04 22:17:20,557 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc972d877cd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 22:17:21,115 unpacking tar archive at: mozilla-aurora-dc972d877cd6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.889777 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:20.755720) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:20.756048) ========= script_repo_revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:20.756463) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:20.756764) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:17:20.767114) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-04 22:17:20.767431) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:17:21 INFO - MultiFileLogger online at 20160604 22:17:21 in /builds/slave/test 22:17:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:17:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:17:21 INFO - {'append_to_log': False, 22:17:21 INFO - 'base_work_dir': '/builds/slave/test', 22:17:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:17:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:17:21 INFO - 'branch': 'Mozilla-Aurora', 22:17:21 INFO - 'buildbot_json_path': 'buildprops.json', 22:17:21 INFO - 'config_files': ('talos/linux_config.py',), 22:17:21 INFO - 'default_actions': ('clobber', 22:17:21 INFO - 'read-buildbot-config', 22:17:21 INFO - 'download-and-extract', 22:17:21 INFO - 'populate-webroot', 22:17:21 INFO - 'create-virtualenv', 22:17:21 INFO - 'install', 22:17:21 INFO - 'run-tests'), 22:17:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:17:21 INFO - 'download_minidump_stackwalk': True, 22:17:21 INFO - 'download_symbols': 'ondemand', 22:17:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:17:21 INFO - 'tooltool.py': '/tools/tooltool.py', 22:17:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:17:21 INFO - '/tools/misc-python/virtualenv.py')}, 22:17:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:17:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:17:21 INFO - 'installer_path': 'installer.exe', 22:17:21 INFO - 'log_level': 'info', 22:17:21 INFO - 'log_name': 'talos', 22:17:21 INFO - 'log_to_console': True, 22:17:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:17:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:17:21 INFO - 'opt_config_files': (), 22:17:21 INFO - 'pip_index': False, 22:17:21 INFO - 'sps_profile': False, 22:17:21 INFO - 'sps_profile_interval': 0, 22:17:21 INFO - 'suite': 'dromaeojs', 22:17:21 INFO - 'system_bits': '32', 22:17:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:17:21 INFO - 'title': 'talos-linux64-ix-006', 22:17:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:17:21 INFO - 'use_talos_json': True, 22:17:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:17:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:17:21 INFO - 'work_dir': 'build'} 22:17:21 INFO - ##### 22:17:21 INFO - ##### Running clobber step. 22:17:21 INFO - ##### 22:17:21 INFO - Running pre-action listener: _resource_record_pre_action 22:17:21 INFO - Running main action method: clobber 22:17:21 INFO - rmtree: /builds/slave/test/build 22:17:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:17:22 INFO - Running post-action listener: _resource_record_post_action 22:17:22 INFO - ##### 22:17:22 INFO - ##### Running read-buildbot-config step. 22:17:22 INFO - ##### 22:17:22 INFO - Running pre-action listener: _resource_record_pre_action 22:17:22 INFO - Running main action method: read_buildbot_config 22:17:22 INFO - Using buildbot properties: 22:17:22 INFO - { 22:17:22 INFO - "project": "", 22:17:22 INFO - "product": "firefox", 22:17:22 INFO - "script_repo_revision": "production", 22:17:22 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:17:22 INFO - "repository": "", 22:17:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:17:22 INFO - "buildid": "20160604190644", 22:17:22 INFO - "pgo_build": "True", 22:17:22 INFO - "basedir": "/builds/slave/test", 22:17:22 INFO - "buildnumber": 283, 22:17:22 INFO - "slavename": "talos-linux64-ix-006", 22:17:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:17:22 INFO - "platform": "ubuntu64_hw", 22:17:22 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:17:22 INFO - "branch": "mozilla-aurora", 22:17:22 INFO - "repo_path": "releases/mozilla-aurora", 22:17:22 INFO - "revision": "dc972d877cd697c2c8f6248534f21bb5e1dba9ae", 22:17:22 INFO - "stage_platform": "linux64-pgo", 22:17:22 INFO - "builduid": "8c8cb62ca25f4a55bba56c381457195b", 22:17:22 INFO - "slavebuilddir": "test" 22:17:22 INFO - } 22:17:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 22:17:22 INFO - Running post-action listener: _resource_record_post_action 22:17:22 INFO - ##### 22:17:22 INFO - ##### Running download-and-extract step. 22:17:22 INFO - ##### 22:17:22 INFO - Running pre-action listener: _resource_record_pre_action 22:17:22 INFO - Running main action method: download_and_extract 22:17:22 INFO - mkdir: /builds/slave/test/build/tests 22:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:22 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:17:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:17:26 INFO - Downloaded 1361 bytes. 22:17:26 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 22:17:26 INFO - Using the following test package requirements: 22:17:26 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 22:17:26 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:17:26 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:17:26 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'jsshell-linux-x86_64.zip'], 22:17:26 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:17:26 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 22:17:26 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:17:26 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:17:26 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:17:26 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 22:17:26 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:17:26 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:26 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:36 INFO - Downloaded 24177288 bytes. 22:17:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:37 INFO - Return code: 0 22:17:37 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:37 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 22:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:39 INFO - Downloaded 24177288 bytes. 22:17:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:40 INFO - Return code: 0 22:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:40 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:17:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 22:17:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 22:17:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 22:17:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:17:42 INFO - Downloaded 10840145 bytes. 22:17:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:17:42 INFO - Return code: 0 22:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:42 INFO - https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:17:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:17:43 INFO - Downloaded 62812559 bytes. 22:17:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:43 INFO - mkdir: /builds/slave/test/properties 22:17:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:17:43 INFO - Writing to file /builds/slave/test/properties/build_url 22:17:43 INFO - Contents: 22:17:43 INFO - build_url:https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 22:17:44 INFO - Running post-action listener: _resource_record_post_action 22:17:44 INFO - Running post-action listener: set_extra_try_arguments 22:17:44 INFO - ##### 22:17:44 INFO - ##### Running populate-webroot step. 22:17:44 INFO - ##### 22:17:44 INFO - Running pre-action listener: _resource_record_pre_action 22:17:44 INFO - Running main action method: populate_webroot 22:17:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:17:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:17:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:17:44 INFO - '--tppagecycles', 22:17:44 INFO - '7'], 22:17:44 INFO - 'tests': ['tsvgm']}}, 22:17:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:17:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:17:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:17:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:17:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['damp', 'tps']}, 22:17:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'tests': ['damp', 'tps']}, 22:17:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['dromaeo_dom']}, 22:17:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:17:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['a11yr', 22:17:44 INFO - 'ts_paint', 22:17:44 INFO - 'tpaint', 22:17:44 INFO - 'sessionrestore', 22:17:44 INFO - 'sessionrestore_no_auto_restore', 22:17:44 INFO - 'tabpaint']}, 22:17:44 INFO - 'other-e10s': {'tests': ['a11yr', 22:17:44 INFO - 'ts_paint', 22:17:44 INFO - 'tpaint', 22:17:44 INFO - 'sessionrestore', 22:17:44 INFO - 'sessionrestore_no_auto_restore', 22:17:44 INFO - 'tabpaint']}, 22:17:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:17:44 INFO - 'ts_paint', 22:17:44 INFO - 'tpaint', 22:17:44 INFO - 'sessionrestore', 22:17:44 INFO - 'sessionrestore_no_auto_restore', 22:17:44 INFO - 'tabpaint']}, 22:17:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:17:44 INFO - 'ts_paint', 22:17:44 INFO - 'tpaint', 22:17:44 INFO - 'sessionrestore', 22:17:44 INFO - 'sessionrestore_no_auto_restore', 22:17:44 INFO - 'tabpaint']}, 22:17:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['a11yr', 22:17:44 INFO - 'ts_paint', 22:17:44 INFO - 'tpaint', 22:17:44 INFO - 'sessionrestore', 22:17:44 INFO - 'sessionrestore_no_auto_restore', 22:17:44 INFO - 'tabpaint']}, 22:17:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['a11yr', 22:17:44 INFO - 'ts_paint', 22:17:44 INFO - 'tpaint', 22:17:44 INFO - 'sessionrestore', 22:17:44 INFO - 'sessionrestore_no_auto_restore', 22:17:44 INFO - 'tabpaint']}, 22:17:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['tsvgx', 22:17:44 INFO - 'tsvgr_opacity', 22:17:44 INFO - 'tart', 22:17:44 INFO - 'tscrollx', 22:17:44 INFO - 'cart']}, 22:17:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:17:44 INFO - 'tsvgr_opacity', 22:17:44 INFO - 'tart', 22:17:44 INFO - 'tscrollx', 22:17:44 INFO - 'cart']}, 22:17:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'talos_options': ['--disable-e10s'], 22:17:44 INFO - 'tests': ['tp5o']}, 22:17:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'tests': ['tp5o']}, 22:17:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'talos_options': ['--disable-e10s', 22:17:44 INFO - '--xperf_path', 22:17:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:44 INFO - 'tests': ['tp5n']}, 22:17:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:44 INFO - 'talos_options': ['--xperf_path', 22:17:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:44 INFO - 'tests': ['tp5n']}}, 22:17:44 INFO - 'talos.zip': {'path': '', 22:17:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:17:44 INFO - Running post-action listener: _resource_record_post_action 22:17:44 INFO - ##### 22:17:44 INFO - ##### Running create-virtualenv step. 22:17:44 INFO - ##### 22:17:44 INFO - Running pre-action listener: _resource_record_pre_action 22:17:44 INFO - Running main action method: create_virtualenv 22:17:44 INFO - Creating virtualenv /builds/slave/test/build/venv 22:17:44 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 22:17:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:17:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:17:44 INFO - Using real prefix '/usr' 22:17:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:17:45 INFO - Installing distribute.............................................................................................................................................................................................done. 22:17:47 INFO - Installing pip.................done. 22:17:47 INFO - Return code: 0 22:17:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:47 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 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:17:47 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 22:17:47 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 22:17:47 INFO - Using env: {'DISPLAY': ':0', 22:17:47 INFO - 'HOME': '/home/cltbld', 22:17:47 INFO - 'LANG': 'en_US.UTF-8', 22:17:47 INFO - 'LANGUAGE': 'en_US:en', 22:17:47 INFO - 'LOGNAME': 'cltbld', 22:17:47 INFO - 'MAIL': '/var/mail/cltbld', 22:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:47 INFO - 'MOZ_NO_REMOTE': '1', 22:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:47 INFO - 'NO_EM_RESTART': '1', 22:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:47 INFO - 'PWD': '/builds/slave/test', 22:17:47 INFO - 'SHELL': '/bin/bash', 22:17:47 INFO - 'SHLVL': '1', 22:17:47 INFO - 'TERM': 'linux', 22:17:47 INFO - 'TMOUT': '86400', 22:17:47 INFO - 'USER': 'cltbld', 22:17:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:47 INFO - '_': '/tools/buildbot/bin/python'} 22:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:47 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 22:17:47 INFO - Cleaning up... 22:17:47 INFO - Return code: 0 22:17:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:47 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:17:47 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>=0.7.1'] in /builds/slave/test/build 22:17:47 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>=0.7.1 22:17:47 INFO - Using env: {'DISPLAY': ':0', 22:17:47 INFO - 'HOME': '/home/cltbld', 22:17:47 INFO - 'LANG': 'en_US.UTF-8', 22:17:47 INFO - 'LANGUAGE': 'en_US:en', 22:17:47 INFO - 'LOGNAME': 'cltbld', 22:17:47 INFO - 'MAIL': '/var/mail/cltbld', 22:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:47 INFO - 'MOZ_NO_REMOTE': '1', 22:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:47 INFO - 'NO_EM_RESTART': '1', 22:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:47 INFO - 'PWD': '/builds/slave/test', 22:17:47 INFO - 'SHELL': '/bin/bash', 22:17:47 INFO - 'SHLVL': '1', 22:17:47 INFO - 'TERM': 'linux', 22:17:47 INFO - 'TMOUT': '86400', 22:17:47 INFO - 'USER': 'cltbld', 22:17:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:47 INFO - '_': '/tools/buildbot/bin/python'} 22:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:47 INFO - Downloading/unpacking psutil>=0.7.1 22:17:47 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 22:17:47 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 22:17:47 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 22:17:47 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 22:17:47 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 22:17:47 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 22:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:17:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:49 INFO - Installing collected packages: psutil 22:17:49 INFO - Running setup.py install for psutil 22:17:49 INFO - building 'psutil._psutil_linux' extension 22:17:49 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 22:17:49 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 22:17:49 INFO - building 'psutil._psutil_posix' extension 22:17:49 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 22:17:49 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 22:17:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:50 INFO - Successfully installed psutil 22:17:50 INFO - Cleaning up... 22:17:50 INFO - Return code: 0 22:17:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:50 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:17:50 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.0.0'] in /builds/slave/test/build 22:17:50 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.0.0 22:17:50 INFO - Using env: {'DISPLAY': ':0', 22:17:50 INFO - 'HOME': '/home/cltbld', 22:17:50 INFO - 'LANG': 'en_US.UTF-8', 22:17:50 INFO - 'LANGUAGE': 'en_US:en', 22:17:50 INFO - 'LOGNAME': 'cltbld', 22:17:50 INFO - 'MAIL': '/var/mail/cltbld', 22:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:50 INFO - 'MOZ_NO_REMOTE': '1', 22:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:50 INFO - 'NO_EM_RESTART': '1', 22:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:50 INFO - 'PWD': '/builds/slave/test', 22:17:50 INFO - 'SHELL': '/bin/bash', 22:17:50 INFO - 'SHLVL': '1', 22:17:50 INFO - 'TERM': 'linux', 22:17:50 INFO - 'TMOUT': '86400', 22:17:50 INFO - 'USER': 'cltbld', 22:17:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:50 INFO - '_': '/tools/buildbot/bin/python'} 22:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:17:50 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 22:17:50 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 22:17:50 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 22:17:50 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 22:17:50 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 22:17:50 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 22:17:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:17:52 INFO - Installing collected packages: mozsystemmonitor 22:17:52 INFO - Running setup.py install for mozsystemmonitor 22:17:52 INFO - Successfully installed mozsystemmonitor 22:17:52 INFO - Cleaning up... 22:17:52 INFO - Return code: 0 22:17:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 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 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:17:52 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 22:17:52 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 22:17:52 INFO - Using env: {'DISPLAY': ':0', 22:17:52 INFO - 'HOME': '/home/cltbld', 22:17:52 INFO - 'LANG': 'en_US.UTF-8', 22:17:52 INFO - 'LANGUAGE': 'en_US:en', 22:17:52 INFO - 'LOGNAME': 'cltbld', 22:17:52 INFO - 'MAIL': '/var/mail/cltbld', 22:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:52 INFO - 'MOZ_NO_REMOTE': '1', 22:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:52 INFO - 'NO_EM_RESTART': '1', 22:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:52 INFO - 'PWD': '/builds/slave/test', 22:17:52 INFO - 'SHELL': '/bin/bash', 22:17:52 INFO - 'SHLVL': '1', 22:17:52 INFO - 'TERM': 'linux', 22:17:52 INFO - 'TMOUT': '86400', 22:17:52 INFO - 'USER': 'cltbld', 22:17:52 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:52 INFO - '_': '/tools/buildbot/bin/python'} 22:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:52 INFO - Downloading/unpacking blobuploader==1.2.4 22:17:52 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 22:17:52 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 22:17:52 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 22:17:52 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 22:17:52 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 22:17:52 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 22:17:54 INFO - Downloading blobuploader-1.2.4.tar.gz 22:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:17:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:17:54 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 22:17:54 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 22:17:54 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 22:17:54 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 22:17:54 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 22:17:54 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 22:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:17:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:17:54 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 22:17:54 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 22:17:54 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 22:17:54 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 22:17:54 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 22:17:54 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 22:17:55 INFO - Downloading docopt-0.6.1.tar.gz 22:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:17:55 INFO - Installing collected packages: blobuploader, requests, docopt 22:17:55 INFO - Running setup.py install for blobuploader 22:17:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:17:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:17:55 INFO - Running setup.py install for requests 22:17:55 INFO - Running setup.py install for docopt 22:17:55 INFO - Successfully installed blobuploader requests docopt 22:17:55 INFO - Cleaning up... 22:17:55 INFO - Return code: 0 22:17:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:55 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 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:17:55 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 22:17:55 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 22:17:55 INFO - Using env: {'DISPLAY': ':0', 22:17:55 INFO - 'HOME': '/home/cltbld', 22:17:55 INFO - 'LANG': 'en_US.UTF-8', 22:17:55 INFO - 'LANGUAGE': 'en_US:en', 22:17:55 INFO - 'LOGNAME': 'cltbld', 22:17:55 INFO - 'MAIL': '/var/mail/cltbld', 22:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:55 INFO - 'MOZ_NO_REMOTE': '1', 22:17:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:55 INFO - 'NO_EM_RESTART': '1', 22:17:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:55 INFO - 'PWD': '/builds/slave/test', 22:17:55 INFO - 'SHELL': '/bin/bash', 22:17:55 INFO - 'SHLVL': '1', 22:17:55 INFO - 'TERM': 'linux', 22:17:55 INFO - 'TMOUT': '86400', 22:17:55 INFO - 'USER': 'cltbld', 22:17:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:55 INFO - '_': '/tools/buildbot/bin/python'} 22:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:56 INFO - Running setup.py (path:/tmp/pip-REuUs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:56 INFO - Running setup.py (path:/tmp/pip-AJMpNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:56 INFO - Running setup.py (path:/tmp/pip-CB51re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:56 INFO - Running setup.py (path:/tmp/pip-Nm7Tab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:56 INFO - Running setup.py (path:/tmp/pip-9br2yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:56 INFO - Running setup.py (path:/tmp/pip-95nhko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:56 INFO - Running setup.py (path:/tmp/pip-CuZhlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:56 INFO - Running setup.py (path:/tmp/pip-ymsHk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:56 INFO - Running setup.py (path:/tmp/pip-2yssYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:56 INFO - Running setup.py (path:/tmp/pip-xVFOyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:56 INFO - Running setup.py (path:/tmp/pip-dOdoTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:56 INFO - Running setup.py (path:/tmp/pip-DEabdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:56 INFO - Running setup.py (path:/tmp/pip-2dgWhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:57 INFO - Running setup.py (path:/tmp/pip-jPTUvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:57 INFO - Running setup.py (path:/tmp/pip-oGfUcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:57 INFO - Running setup.py (path:/tmp/pip-ztqlNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:57 INFO - Running setup.py (path:/tmp/pip-dm3k5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:17:57 INFO - Running setup.py install for manifestparser 22:17:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:17:57 INFO - Running setup.py install for mozcrash 22:17:57 INFO - Running setup.py install for mozdebug 22:17:57 INFO - Running setup.py install for mozdevice 22:17:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:17:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:17:57 INFO - Running setup.py install for mozfile 22:17:57 INFO - Running setup.py install for mozhttpd 22:17:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:17:57 INFO - Running setup.py install for mozinfo 22:17:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Running setup.py install for mozInstall 22:17:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Running setup.py install for mozleak 22:17:58 INFO - Running setup.py install for mozlog 22:17:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Running setup.py install for moznetwork 22:17:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Running setup.py install for mozprocess 22:17:58 INFO - Running setup.py install for mozprofile 22:17:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Running setup.py install for mozrunner 22:17:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:17:58 INFO - Running setup.py install for mozscreenshot 22:17:58 INFO - Running setup.py install for moztest 22:17:58 INFO - Running setup.py install for mozversion 22:17:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:17:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:17:59 INFO - Cleaning up... 22:17:59 INFO - Return code: 0 22:17:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:59 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 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:17:59 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 22:17:59 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 22:17:59 INFO - Using env: {'DISPLAY': ':0', 22:17:59 INFO - 'HOME': '/home/cltbld', 22:17:59 INFO - 'LANG': 'en_US.UTF-8', 22:17:59 INFO - 'LANGUAGE': 'en_US:en', 22:17:59 INFO - 'LOGNAME': 'cltbld', 22:17:59 INFO - 'MAIL': '/var/mail/cltbld', 22:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:59 INFO - 'MOZ_NO_REMOTE': '1', 22:17:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:59 INFO - 'NO_EM_RESTART': '1', 22:17:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:59 INFO - 'PWD': '/builds/slave/test', 22:17:59 INFO - 'SHELL': '/bin/bash', 22:17:59 INFO - 'SHLVL': '1', 22:17:59 INFO - 'TERM': 'linux', 22:17:59 INFO - 'TMOUT': '86400', 22:17:59 INFO - 'USER': 'cltbld', 22:17:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:59 INFO - '_': '/tools/buildbot/bin/python'} 22:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:59 INFO - Running setup.py (path:/tmp/pip-K22R7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:59 INFO - Running setup.py (path:/tmp/pip-bmBnRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:59 INFO - Running setup.py (path:/tmp/pip-swcJAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:59 INFO - Running setup.py (path:/tmp/pip-m5BRGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:59 INFO - Running setup.py (path:/tmp/pip-seFqgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:59 INFO - Running setup.py (path:/tmp/pip-rdIpVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:59 INFO - Running setup.py (path:/tmp/pip-rYb9UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:59 INFO - Running setup.py (path:/tmp/pip-0t6svK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:59 INFO - Running setup.py (path:/tmp/pip-BemSkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:59 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)) 22:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:59 INFO - Running setup.py (path:/tmp/pip-qp9P14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 22:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:18:00 INFO - Running setup.py (path:/tmp/pip-jzzHFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:18:00 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)) 22:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:18:00 INFO - Running setup.py (path:/tmp/pip-gXAT7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 22:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:18:00 INFO - Running setup.py (path:/tmp/pip-onvDoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:18:00 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)) 22:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:18:00 INFO - Running setup.py (path:/tmp/pip-b6Wm3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 22:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:18:00 INFO - Running setup.py (path:/tmp/pip-0RIcVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:18:00 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)) 22:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:18:00 INFO - Running setup.py (path:/tmp/pip-a9Ltxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:18:00 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)) 22:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:18:00 INFO - Running setup.py (path:/tmp/pip-_IwEx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:18:00 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)) 22:18:00 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)) 22:18:00 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)) 22:18:00 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)) 22:18:00 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)) 22:18:00 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)) 22:18:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:02 INFO - Downloading blessings-1.6.tar.gz 22:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:18:02 INFO - Installing collected packages: blessings 22:18:02 INFO - Running setup.py install for blessings 22:18:02 INFO - Successfully installed blessings 22:18:02 INFO - Cleaning up... 22:18:02 INFO - Return code: 0 22:18:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:18:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:18:02 INFO - Reading from file tmpfile_stdout 22:18:02 INFO - Current package versions: 22:18:02 INFO - argparse == 1.2.1 22:18:02 INFO - blessings == 1.6 22:18:02 INFO - blobuploader == 1.2.4 22:18:02 INFO - docopt == 0.6.1 22:18:02 INFO - manifestparser == 1.1 22:18:02 INFO - mozInstall == 1.12 22:18:02 INFO - mozcrash == 0.17 22:18:02 INFO - mozdebug == 0.1 22:18:02 INFO - mozdevice == 0.48 22:18:02 INFO - mozfile == 1.2 22:18:02 INFO - mozhttpd == 0.7 22:18:02 INFO - mozinfo == 0.9 22:18:02 INFO - mozleak == 0.1 22:18:02 INFO - mozlog == 3.1 22:18:02 INFO - moznetwork == 0.27 22:18:02 INFO - mozprocess == 0.22 22:18:02 INFO - mozprofile == 0.28 22:18:02 INFO - mozrunner == 6.11 22:18:02 INFO - mozscreenshot == 0.1 22:18:02 INFO - mozsystemmonitor == 0.0 22:18:02 INFO - moztest == 0.7 22:18:02 INFO - mozversion == 1.4 22:18:02 INFO - psutil == 3.1.1 22:18:02 INFO - requests == 1.2.3 22:18:02 INFO - wsgiref == 0.1.2 22:18:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:18: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')]} 22:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18: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')]} 22:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:02 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 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:18:02 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 22:18:02 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 22:18:02 INFO - Using env: {'DISPLAY': ':0', 22:18:02 INFO - 'HOME': '/home/cltbld', 22:18:02 INFO - 'LANG': 'en_US.UTF-8', 22:18:02 INFO - 'LANGUAGE': 'en_US:en', 22:18:02 INFO - 'LOGNAME': 'cltbld', 22:18:02 INFO - 'MAIL': '/var/mail/cltbld', 22:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:02 INFO - 'MOZ_NO_REMOTE': '1', 22:18:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:02 INFO - 'NO_EM_RESTART': '1', 22:18:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:02 INFO - 'PWD': '/builds/slave/test', 22:18:02 INFO - 'SHELL': '/bin/bash', 22:18:02 INFO - 'SHLVL': '1', 22:18:02 INFO - 'TERM': 'linux', 22:18:02 INFO - 'TMOUT': '86400', 22:18:02 INFO - 'USER': 'cltbld', 22:18:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:02 INFO - '_': '/tools/buildbot/bin/python'} 22:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 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)) 22:18:03 INFO - Cleaning up... 22:18:03 INFO - Return code: 0 22:18:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:18:03 INFO - Using env: {'DISPLAY': ':0', 22:18:03 INFO - 'HOME': '/home/cltbld', 22:18:03 INFO - 'LANG': 'en_US.UTF-8', 22:18:03 INFO - 'LANGUAGE': 'en_US:en', 22:18:03 INFO - 'LOGNAME': 'cltbld', 22:18:03 INFO - 'MAIL': '/var/mail/cltbld', 22:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:03 INFO - 'MOZ_NO_REMOTE': '1', 22:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:03 INFO - 'NO_EM_RESTART': '1', 22:18:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:03 INFO - 'PWD': '/builds/slave/test', 22:18:03 INFO - 'SHELL': '/bin/bash', 22:18:03 INFO - 'SHLVL': '1', 22:18:03 INFO - 'TERM': 'linux', 22:18:03 INFO - 'TMOUT': '86400', 22:18:03 INFO - 'USER': 'cltbld', 22:18:03 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:03 INFO - '_': '/tools/buildbot/bin/python'} 22:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:03 INFO - Downloading/unpacking jsonschema 22:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:18:05 INFO - Downloading/unpacking functools32 (from jsonschema) 22:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:05 INFO - Downloading functools32-3.2.3-2.tar.gz 22:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:18:05 INFO - warning: no files found matching '*.txt' 22:18:05 INFO - no previously-included directories found matching 'build' 22:18:05 INFO - no previously-included directories found matching 'dist' 22:18:05 INFO - no previously-included directories found matching '.git*' 22:18:05 INFO - Installing collected packages: jsonschema, functools32 22:18:05 INFO - Running setup.py install for functools32 22:18:05 INFO - warning: no files found matching '*.txt' 22:18:05 INFO - no previously-included directories found matching 'build' 22:18:05 INFO - no previously-included directories found matching 'dist' 22:18:05 INFO - no previously-included directories found matching '.git*' 22:18:05 INFO - Successfully installed jsonschema functools32 22:18:05 INFO - Cleaning up... 22:18:05 INFO - Return code: 0 22:18:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:18:05 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b63957f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', '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 22:18:05 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', 'flake8'] in /builds/slave/test/build 22:18:05 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 flake8 22:18:05 INFO - Using env: {'DISPLAY': ':0', 22:18:05 INFO - 'HOME': '/home/cltbld', 22:18:05 INFO - 'LANG': 'en_US.UTF-8', 22:18:05 INFO - 'LANGUAGE': 'en_US:en', 22:18:05 INFO - 'LOGNAME': 'cltbld', 22:18:05 INFO - 'MAIL': '/var/mail/cltbld', 22:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:05 INFO - 'MOZ_NO_REMOTE': '1', 22:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:05 INFO - 'NO_EM_RESTART': '1', 22:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:05 INFO - 'PWD': '/builds/slave/test', 22:18:05 INFO - 'SHELL': '/bin/bash', 22:18:05 INFO - 'SHLVL': '1', 22:18:05 INFO - 'TERM': 'linux', 22:18:05 INFO - 'TMOUT': '86400', 22:18:05 INFO - 'USER': 'cltbld', 22:18:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:05 INFO - '_': '/tools/buildbot/bin/python'} 22:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:18:05 INFO - Downloading/unpacking flake8 22:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:18:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:18:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:18:07 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 22:18:07 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 22:18:07 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 22:18:07 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 22:18:07 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 22:18:07 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 22:18:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:18:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:18:07 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 22:18:07 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 22:18:07 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 22:18:07 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 22:18:07 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 22:18:07 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 22:18:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:18:08 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 22:18:08 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 22:18:08 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 22:18:08 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 22:18:08 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 22:18:08 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 22:18:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:18:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:18:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:18:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:18:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:18:08 INFO - no previously-included directories found matching 'docs/_build' 22:18:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:18:08 INFO - Running setup.py install for pep8 22:18:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:18:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:18:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:18:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:18:08 INFO - no previously-included directories found matching 'docs/_build' 22:18:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:18:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:18:08 INFO - Cleaning up... 22:18:08 INFO - Return code: 0 22:18:08 INFO - Running post-action listener: _resource_record_post_action 22:18:08 INFO - Running post-action listener: _start_resource_monitoring 22:18:08 INFO - Starting resource monitoring. 22:18:08 INFO - ##### 22:18:08 INFO - ##### Running install step. 22:18:08 INFO - ##### 22:18:08 INFO - Running pre-action listener: _resource_record_pre_action 22:18:08 INFO - Running main action method: install 22:18:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:18:08 INFO - Reading from file tmpfile_stdout 22:18:08 INFO - Detecting whether we're running mozinstall >=1.0... 22:18:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:18:09 INFO - Reading from file tmpfile_stdout 22:18:09 INFO - Output received: 22:18:09 INFO - Usage: mozinstall [options] installer 22:18:09 INFO - Options: 22:18:09 INFO - -h, --help show this help message and exit 22:18:09 INFO - -d DEST, --destination=DEST 22:18:09 INFO - Directory to install application into. [default: 22:18:09 INFO - "/builds/slave/test"] 22:18:09 INFO - --app=APP Application being installed. [default: firefox] 22:18:09 INFO - mkdir: /builds/slave/test/build/application 22:18:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:18:24 INFO - Reading from file tmpfile_stdout 22:18:24 INFO - Output received: 22:18:24 INFO - /builds/slave/test/build/application/firefox/firefox 22:18:24 INFO - Running post-action listener: _resource_record_post_action 22:18:24 INFO - ##### 22:18:24 INFO - ##### Running run-tests step. 22:18:24 INFO - ##### 22:18:24 INFO - Running pre-action listener: _resource_record_pre_action 22:18:24 INFO - Running main action method: run_tests 22:18:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:18:24 INFO - Python 2.7.3 22:18:24 INFO - Return code: 0 22:18:24 INFO - grabbing minidump binary from tooltool 22:18: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')]} 22:18:24 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2587410>, '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 0x2588310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25887a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:18:24 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 22:18:24 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 22:18:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:18:24 INFO - Return code: 0 22:18:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:18:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:18:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:18:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:18:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:18:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:18:25 INFO - Return code: 0 22:18:25 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/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 22:18:25 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/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 22:18:25 INFO - Using env: {'DISPLAY': ':0', 22:18:25 INFO - 'HOME': '/home/cltbld', 22:18:25 INFO - 'LANG': 'en_US.UTF-8', 22:18:25 INFO - 'LANGUAGE': 'en_US:en', 22:18:25 INFO - 'LOGNAME': 'cltbld', 22:18:25 INFO - 'MAIL': '/var/mail/cltbld', 22:18:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:18:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:25 INFO - 'MOZ_NO_REMOTE': '1', 22:18:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:18:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:25 INFO - 'NO_EM_RESTART': '1', 22:18:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:25 INFO - 'PWD': '/builds/slave/test', 22:18:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:18:25 INFO - 'SHELL': '/bin/bash', 22:18:25 INFO - 'SHLVL': '1', 22:18:25 INFO - 'TERM': 'linux', 22:18:25 INFO - 'TMOUT': '86400', 22:18:25 INFO - 'USER': 'cltbld', 22:18:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337', 22:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:25 INFO - '_': '/tools/buildbot/bin/python'} 22:18:25 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/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 22:18:25 INFO - mozversion application_buildid: 20160604190644 22:18:25 INFO - mozversion application_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 22:18:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:18:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:18:25 INFO - mozversion application_name: Firefox 22:18:25 INFO - mozversion application_remotingname: firefox-dev 22:18:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:18:25 INFO - mozversion application_vendor: Mozilla 22:18:25 INFO - mozversion application_version: 48.0a2 22:18:25 INFO - mozversion platform_buildid: 20160604190644 22:18:25 INFO - mozversion platform_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 22:18:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:18:25 INFO - mozversion platform_version: 48.0a2 22:18:25 INFO - using testdate: 1465103905 22:18:25 INFO - actual date: 1465103905 22:18:25 INFO - starting webserver on 'localhost:37001' 22:18:25 INFO - SUITE-START | Running 2 tests 22:18:25 INFO - TEST-START | dromaeo_css 22:18:25 INFO - operating with platform_type : linux_ 22:18:25 INFO - Initialising browser for dromaeo_css test... 22:18:25 INFO - TEST-INFO | started process 4187 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBmbfVk/profile http://localhost:37001/getInfo.html) 22:18:29 INFO - PROCESS | 4187 | __metrics Screen width/height:1600/1200 22:18:29 INFO - PROCESS | 4187 | colorDepth:24 22:18:29 INFO - PROCESS | 4187 | Browser inner width/height: 1024/697 22:18:29 INFO - PROCESS | 4187 | __metrics 22:18:30 ERROR - PROCESS | 4187 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:18:31 INFO - TEST-INFO | 4187: exit 0 22:18:31 INFO - Browser initialized. 22:18:31 INFO - Running cycle 1/1 for dromaeo_css test... 22:18:31 INFO - TEST-INFO | started process 4285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBmbfVk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:18:42 INFO - PROCESS | 4285 | RSS: Main: 142049280 22:18:42 INFO - PROCESS | 4285 | 22:19:34 INFO - PROCESS | 4285 | 1465103974624 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] 22:21:41 INFO - PROCESS | 4285 | Cycle 1(1): loaded http://localhost:37001/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37001/tests/dromaeo/cssquery-ext.html) 22:21:42 INFO - PROCESS | 4285 | RSS: Main: 251990016 22:21:42 INFO - PROCESS | 4285 | 22:24:44 INFO - PROCESS | 4285 | Cycle 1(1): loaded http://localhost:37001/tests/dromaeo/cssquery-ext.html (next: http://localhost:37001/tests/dromaeo/cssquery-jquery.html) 22:24:45 INFO - PROCESS | 4285 | RSS: Main: 228593664 22:24:45 INFO - PROCESS | 4285 | 22:27:46 INFO - PROCESS | 4285 | Cycle 1(1): loaded http://localhost:37001/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37001/tests/dromaeo/cssquery-mootools.html) 22:27:47 INFO - PROCESS | 4285 | RSS: Main: 220024832 22:27:47 INFO - PROCESS | 4285 | 22:30:49 INFO - PROCESS | 4285 | Cycle 1(1): loaded http://localhost:37001/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37001/tests/dromaeo/cssquery-prototype.html) 22:30:49 INFO - PROCESS | 4285 | RSS: Main: 221540352 22:30:49 INFO - PROCESS | 4285 | 22:33:52 INFO - PROCESS | 4285 | Cycle 1(1): loaded http://localhost:37001/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37001/tests/dromaeo/cssquery-yui.html) 22:33:53 INFO - PROCESS | 4285 | RSS: Main: 185790464 22:33:53 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | Cycle 1(1): loaded http://localhost:37001/tests/dromaeo/cssquery-yui.html (next: http://localhost:37001/tests/dromaeo/cssquery-dojo.html) 22:36:55 INFO - PROCESS | 4285 | RSS: Main: 256471040 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | __start_tp_report 22:36:55 INFO - PROCESS | 4285 | _x_x_mozilla_page_load 22:36:55 INFO - PROCESS | 4285 | _x_x_mozilla_page_load_details 22:36:55 INFO - PROCESS | 4285 | |i|pagename|runs| 22:36:55 INFO - PROCESS | 4285 | |0;dojo.html;3318.236805534727;3366.2257680733414;3370.039538731462;3371.2246183377815;3411.3518431498014;5498.697571633558;5499;5504.394516603171;5508.669479831213;5510.476504732054;5462.20797984293;5480.095784195613;5496.477834605706;5507.118860982244;5533.0870406383;4942.18453955097;4958.528939750718;5057.41839688435;5105.259737338084;5129.358830146231;5005.224190250517;5065.493450654942;5114.079465696173;5136.332276804011;5145.073886700392;5116.386033675945;5126.692398456086;5137.023965446572;5139.794408223666;5166.095933211692;5020.422651395063;5056.84829455117;5069.873253168664;5127.02586508564;5131.692098474085;4985.077760614262;5039.924401133986;5040.79836806531;5042.697438153723;5060.873478163038;5416.647917885325;5417.674939503642;5440.510354068113;5460.208269800856;5482.91775623366;5878.470937615635;5889.293284805808;5903.970480147572;5904.084866845646;5905.940940590582;6014.879702405928;6033.336333003366;6038.788642397539;6040.909386359208;6069.119977603223;5619.044762390404;5623.325200975909;5624.0439125348785;5660.830175094754;5664.150377443417;6023.578349515492;6023.909641355295;6131.417515336036;6147.323794382614;6147.477464415485;5891.882162356815;5897.174395584621;5904.409559044061;5907.763689452374;5930.466258036798;5967.731452084667;5977.402259773988;5977.701114944322;5978.581499295007;5978.701064946736;5838.474537291664;5893.44012318829;5955;5956.315023772235;6027.578069535178;5834.649921004753;5869.236999190163;5869.354371019184;5869.853253668693;5909.3795151508475;4503.7522936238165;4503.977480112644;4513.165064463019;4521.231390663595;4533.342665313511;3987.900302492461;4053.817578208929;4060.3300455425115;4123.60825721556;4158.500979882433;5697.145428185722;5700.315962084576;5704.686242256633;5704.828855134353;5713.571482138773;5981.641101533922;5983.880322393615;5987.43119403656;5987.491063259672;5989.431004054608;5607.214989901417;5682.40334764852;5709.400512946081;5772.538196944234;5777.88444231113;5099.388073431138;5107.310513080686;5124.538791508708;5129.179331306973;5142.460041695643;6007.609505382136;6182.381761823782;6184.536159788034;6191.628502289877;6193.907091393633;5921.3190483094995;5969.880602388015;5983.730732117152;6026.517878569616;6026.578139530367;6185.783497577563;6201.10084037821;6218.129461875251;6221.720022599085;6222.657753823402;5738.655680659007;5747.1379293106365;5878.764849406147;5888.646681199141;5889.558283128868;5884.35272120075;5939.465448109518;5940.79207227745;5956.136360227656;5997.640141591519;5760.107183386582;5801.129830525455;5805.303363596396;5813.476787089013;5815.651060936358;5576.414476479911;5746.942530574641;5756.194132821324;5808.593398462067;5852.795152169653;5663.915041274467;5669.886602268014;5676.091825307931;5696.231008813757;5717.3996730342715;5788.305403351625;5792.53659707214;5793.275840519935;5803.8549036274435;5810.854728631649;5675;5684.573656975661;5718.571107166896;5786.450287222707;5829.504492118217 22:36:55 INFO - PROCESS | 4285 | |1;ext.html;615.6213928434014;658.9374009469101;677.9796606101816;678.7217240931219;757.9880859053163;44555;45635.08729825405;45672.543274567244;45689.77155114228;45708.3143752844;48728.2690759638;49381.50618493814;49410.25884611725;49415.50584494154;49446.75276623621;8024.879626805603;9004.234640055603;9302.069793020677;9306.581203845843;10487.056164945154;10565.154787616972;10694.304870183452;10704.518296676668;10706.250562460631;10707.839382409271;10991.175661825355;11087.667369978873;11089.445527723621;11091.389973551451;11095.944520277428;8194.30348420382;10884.401357925311;10936.398498082603;11116.110711143088;11116.722081947935;10538.420386878719;10606.515087887701;10632.36205827645;10658.360498370044;10661.360318380923;44512.55487445116;44926.775366123285;45048.77475612601;45146.097078058585;45162;3742.5134732484876;3755.239898674472;3822.0635944193573;4061.3095773718537;4061.6547593454297;31717.84141079303;31728.524072138935;32059.679403206137;32390.190245243823;33280.33439331224;4358.237308471005;4362.17118747437;4416.4258646375765;4436.268015777393;4517.164324599959;236499;237305;237425;237774;238162;39921.60078399237;40753;66471.66764166148;66565;66565;38981.6101838978;39032.462889912065;39074.41388379176;39126.41310380347;39147.608523914394;16139.193040347936;16195.595110122105;16312.673746525003;16333.26500307489;16366.099864507567;13640.249786261653;14812.111273323635;15123.697526049638;15135.394584216729;15136;9328.160465558132;9353.719388418358;9357.204637605744;9377.156055954996;9411;3588.120910376922;3596.1009747563107;3601.4417765245903;3607.494950706903;3682.8895133146048;1068.6793961811582;1111.5478577576998;1155.1163360029584;1169.5964892112208;1201.1531870031606;33828;34355.870930527286;34802.30395392078;35009.64990350115;35074.29851403009;1205.867354591012;1236.6908272931767;1243.3720970909633;1307.7711400504872;1322.3916998180753;17696.91151544234;18017.549561261072;18234.538337324557;18343.449696509128;18696.53258668517;483452;488435;488454;491043;491267;64388;65327.67336163384;65404;65486;65572;1323.7617228898891;1394.3795011220113;1426.8573142685857;1486.3162945045185;1600.6878658661683;30695.2326191847;32176.517352240204;32750.672493274764;32799;33467.83266083654;48300;48695.26957095575;48696;48805.755971219914;48811.511884880696;250082;250639;251054;251327;252411;29709;48089.27866082082;48203;48785.02429951452;49397.506024940725;47427.762861185474;47619.523804761506;48477;50447.24329134992;51754.482455176476;42190.36714449347;42715;44204.11591768082;44520.77739611411;45076;41487.377689335284;41771.373429399195;42555.57444425516;43057;43391.13217735691;40526.79736601298;45630.31554526618;45843;47176.29235561538;47264.2910356338;33855.830720846236;35820;36025.27949441049;36257.45613815848;36561.45157822688;27150.592741109293;27414.5887811687;27628.171154865388;27631.30921726973;27747 22:36:55 INFO - PROCESS | 4285 | |2;jquery.html;435.1731709751474;440.8654466741469;440.96813456511757;441.5849101844267;452.812082985561;20218.09018594163;20287.391378258657;21043.894780526094;21053.78946210541;21060.36818895432;20520.89739551304;20569.28007519737;20692.48268793281;20862.374128776122;20892.8955355223;5530.751116199781;5743.396943320933;5785.508231800281;5789;5791.07342825148;4225.239456897757;4829.396325459317;5910.88178236435;6139.723712432951;6466.612003279818;6465.903011454832;6492.870142597145;6508.772192973247;6518.120053792748;6520.347965203489;6493.383128602799;6860.62787442511;7204.063471748692;7223.602701851396;7223.674934627954;6894.413974812147;6908.309169083102;6961.860762784716;6999.685014174374;7025;19687.606247875105;25345.74654253452;25930;26922.5961610576;26952.865235673893;2823.110720123155;2841.4033053058674;3095.9535606965915;3165.303633200715;3329.467285234375;15678.608034799106;16404.17979101052;16411.58971025722;16643.583910402216;16974.15129243533;2218.456478162844;2276.1350686739097;2289.050044231642;2291.9197828076103;2318.6637937498967;96589;97667.51166244266;99639.50180249124;101396.49301753518;101604;18315.54211144732;18850.81149188518;19155;19256.22975081009;19540.70688939667;35201.82399087988;35330;35418.46872296918;35859.82070089685;35896;8196.508209507449;8592.828143437222;8648.308135349283;9220.8616870747;9315.813683726423;9126.45241285525;9143.085691430937;9207.17135457812;9208.677696280733;9256.490893000815;5051.444341122474;5118.820841270538;5193.714345710947;5203.7918483261;5240.109181439165;1892.0256068124927;2015.2845739762467;2071.523549583574;2161.8378621603442;2182.792634699733;998.7453199434098;1018.4805749067881;1021.6934919524113;1173.8650055243586;1174.6123779152858;17965.640687186184;18541.856865002163;19544.80455195458;19550.31573894907;19953;1072.4101744040902;1076.4187338837096;1094.3051162511863;1120.3613940054254;1136.6303604156972;10788.838167427495;11130.38782866934;11216.383098929637;11221.214514984036;11323.660290191307;522552;528303;529641;535103;547858;94686;98407.50796246117;98598;98940.50529747449;99739.50130249302;1108.0636861851722;1133.393634405589;1147.300146910384;1163.9243449175947;1164.8602167739925;16982.915085424494;17156.571085722957;17186.742198866777;17254.913725431794;17318.740218896473;40204.1959160821;41396;42230.78884605557;42530.57469425266;42645;60671;60947.69526152341;60980.08529871967;61246.08130877951;62246;8598.82802343962;8864.290856731575;8902.59938302791;8917.643294268157;9069.22911552499;9777.853332200166;9851.802963940825;10004.599816007278;13725.450981961008;13818.447262109403;6193.969030154821;9348.95325523368;10714.357138571711;12180.695482613006;12381.75236495283;11948.095971605271;12135.575254866037;12257.325847078497;12461.501539938663;12470.750584988431;12229.3273869938;12258.080643951918;12432.627021189379;12570.811437828257;12723.618291451272;9256.120668536478;9331.253499720142;9379.577918993798;9512.857307140537;9579.089986451276;12288.447019883948;12498.187617804813;12524.499020039097;12559.811602826148;12608.49566017349 22:36:55 INFO - PROCESS | 4285 | |3;mootools.html;1418.588609303302;1588.3249618911962;1620.206099011484;1860.2280053777683;1930.9227630894766;8551.828963420729;8754.737357879274;8760.518171500566;8766.474011559303;8766.517841518722;8295.170482951702;8444.4511106778;8483.66065357385;8485.151484851527;8485.660573577064;2144.3781303422065;2410.505846301513;2439.963400548989;2461.4215659320043;2461.5692253855505;2372.1697405907967;2376.9049238030457;2380.797632201265;2382.523495300938;2384.4873352229265;2418.8669623170717;2436.8537887726793;2498.038255271724;2502.1492692484553;2504.0860086068637;2107.6100921329526;2415.3599296186526;2427.6965379327585;2459.9754002460104;2470.7035155781423;2330.7669233076726;2366.9053237870467;2423.551642946043;2426.745191754858;2428.5264373447185;8690.522021288827;8705.608247628808;8740.300775937973;8740.694075707319;8742.519161446118;5393.352797664305;5484.451554844523;5492.066348720727;5502.422245664227;5506.531944784698;3517.82410879455;3589.982050089759;3616.2948225096115;3742.0831896185337;3761.6238376162437;1125.4260327233087;1126.4705588373452;1134.4781400555755;1146.9541218351255;1248.3695733654538;12579.433925473284;12652.240865548096;12680.556180533637;12688.555900543437;12708.237505749592;4915.262710593368;4986.077575648516;4999.975000125049;5022.949770502249;5046.34397528319;10784.08335291509;10820;10837.67486975392;10849.294795838245;10857.83713244286;5707.600467967285;5709.714514274269;5710.428957104339;5712.571557133231;5728.198052272602;4506.729596224237;5017.82437614689;5046.1169295373165;5061.848144555669;5116.872078198075;4242.936355954663;4243.575642435793;4298.957010429918;4349.304111342233;4351.4995775485595;1347.6832944258092;1391.4086513231837;1395.8813500852339;1396.8882489400823;1405.107756574562;935.7473482159779;1020.2042406922612;1032.3341444768232;1036.3729943384294;1069.7165251208437;5859.2382990211;6035.1249068885645;6092.786752463642;6171.660558669227;6217.844553886099;965.7344230336573;1107.4739498738195;1137.6985098948785;1170.449888552379;1170.8946194842335;3849.7882616456363;3855.845766169378;4003.2393845169327;4071.1654110907193;4204.600562946576;10497.212709046851;10710.571577136983;10789.514471848786;10886.346819190874;10904.781904362027;10487.213458990454;10693.41186234749;10858.782824343627;10878.72803179911;10881.891181088246;8412.284955778827;8550.957245213734;8552.102029286836;8605.784855378666;8608.741737747878;5866.442687944638;5874.236349274567;5900.350961394329;5998.770252098222;6016.699165041643;9653.227741780782;9686.854697179408;9800.607975680892;9941.602335906482;9969;9894.406335619598;9900.158486528726;10067.1442927352;10110.039546243095;10163.339382940085;6759.831004224934;7000.964995174992;7004.019437278683;7445.553266804009;7651.120121186135;8201.589920503831;8368.83262334762;8387.329013679013;8402.24379805796;8408.075111737826;7547.132079810892;7680.500767450098;7723;7771.650275737492;7800.765977020698;7541.170471248049;7589.316961473494;7630.847383052197;7692;7710.074791025113;7014.5440546364325;7089.7873063808165;7096.254893236136;7182.497225194186;7239.022731931333;5358.356997160366;5370.436104209002;5458.2904222450825;5529.917051244315;5553.250311207935;5043.268726034631;5144.231961703411;5189.428058144757;5320.600954928474;5368.8389348319615 22:36:55 INFO - PROCESS | 4285 | |4;prototype.html;361.6925613228756;370.16527729968925;372.099519163624;377.80165413158073;380.1750202061528;6131.202943617323;6204.193454850869;6234.251889773233;6251.374862513758;6294.307626161119;6062.939370606293;6155.768846230761;6167.691615419233;6232.407921247485;6275.5920865143735;4830.444498882621;4833.10587541306;4846.41842978843;4847.442544107437;4847.660663753539;4595.103954728829;4712.151812673735;4791.688540244873;4843.539863712942;4845.733484658343;4747.4540427850725;4749.287606858981;4765.380500534926;4772.785224664881;4779.115863565252;4742.691725037878;4742.95257047432;4749.050189961987;4758.976205118987;4765.499622539618;4262.658987281041;4271.252530807096;4275.850345237902;4280.550542193056;4284.871453856358;5995.910061349084;6008.729607167688;6012.5791194616395;6021.187139736124;6026.548008899306;5426.294581704393;5609.270794796691;5627.155926611;5627.943720562783;5634.154876768476;6575.868482630321;6605;6621.668916554153;6638.634875081869;6643.1363922690225;4772.904541909177;4779.259214821674;4779.832705855279;4784.8994731212015;4786.21027530457;6773.695183716744;6852.897206541856;6930.30696930308;6957.547759395623;6961.582305061713;4710.8351207707565;4727.172744769651;4739.905201895943;4740.834070807505;4743.83396581118;4773.451053128865;4792.664513484093;4794;4795.664303498722;4801.039792041536;5523.917141242885;5530.751116199781;5535.059039963216;5539.639923404966;5543.251661025791;6652.201735791736;6659.300773418819;6693.9918024592425;6725.932740672629;6737.52837301394;1659.7593348964574;1702.4977631598708;1736.4096207289583;1738.1309345327336;1748.361847925498;1158.7798318319492;1177.375990724917;1186.655869797749;1188.1445359341433;1189.7679952409196;6428.55000149985;6442.710078046499;6449.097126402307;6453.386928241904;6457.61254324742;6809.250982391933;6818.352256535622;6819.352161544645;6822.488313376417;6822.692978815965;6429.742810287629;6446.323136070645;6446.7421303148285;6448.709808058648;6462.903056454157;3653.324135035027;3656.670899619047;3686.299603075407;3686.760360576554;3688.944665830015;6857.725690972405;7026.648667566601;7059.505834591632;7083.0437890885105;7086.893696594555;6926.826829329307;6946.791596252019;6951.165860096719;6953.269906659829;6964.616946068015;7102.183248926336;7104.502684812013;7110.466714996396;7116.537425067354;7123.928760712534;6788.59268443895;6796.35434633709;6803.659817009228;6804.591724496546;6813.829654258683;6562.409383155539;6678.031685405686;6723.865522689616;6731.32686731323;6743.1908171019795;7090.07828982229;7097.077379940574;7105.928940710527;7130.928690713234;7136.4647651427385;4892.975535122301;4895.853124406273;4903.5586797188425;4905.190643543918;4907.092187945242;6564.671766411756;6718.462522998246;6769.830754230987;6770.4922130839395;6772.762953296611;6707.228668702966;6724.495662825406;6738.719643267764;6743.055972163999;6744.595324280559;6693.129893114059;6699.162604674416;6701.229358623821;6708.8993665094085;6714.83212919681;6543.901841472287;6578.5395022347975;6583.242927063257;6588.769393071219;6597.670116494252;6647.069410282467;6696.899546506904;6740.865182696417;6750.5274630775375;6811.284815094542;6503.6422996733745;6529.608223506605;6546.738130474728;6565.277819439953;6568.967155164193 22:36:55 INFO - PROCESS | 4285 | |5;yui.html;925.5881132895862;935.6062300835681;942.100294219021;947.6493697331988;970.6505657963132;15106.697866042687;15500.302486388111;15599.06405615661;15619.375224991016;15631.765523517142;14477.348519316629;14574.635634109152;14579.708405831878;14610;14630.926845365784;741.6291854072977;912.0788004115824;935.9064093590621;942.9245660347156;946.4983558713893;925.4586067150725;932.114491233331;934.6401635370397;934.9485778282192;935.7847695030147;993.9652112176075;995.0944640377259;995.5470261031213;996.3922007575374;997.1175509673955;808.6361137488125;971.4268581536892;985.9704208873745;990.6928852055813;990.9653162139326;724.7572063358781;725.3435640745134;725.361681720083;725.760499035317;726.2483329753733;14651.63370915725;14724.705505889822;14743.77884331736;14773.556793296111;14779.704405911929;15018.924905375403;15034.323455444532;15377;15447.382104715798;15509.689806203925;12050.216735912138;12094.274343539328;12265.202761820454;12336.136470446983;12402.379881005914;4682.719036857799;4699.694519856198;4708.529147085298;4711.552402521755;4782.760861956923;54514.45485545133;54862.72568637131;55116.72441637806;55180;56133;11269.492872820743;11417.37204453763;11490.138239631893;11523.884761152281;11683.065354771597;20603.79396206049;20612.381628551168;20854.37436876896;20904;20908.37274881756;8605.913940860637;8783.472991620396;8814.691485797966;8833.911660883437;9001.369904106781;4899.730514821719;5006.799728010838;5011.674241174312;5030.773615187325;5034.194528875436;3584.946225806615;3644.6902013329163;3648.4892115103908;3663.780173189564;3667;664.1631544254275;674.8886433738477;685.873113474009;687.1410736579276;742.3393180069743;732.3701616609711;738.9630518474055;799.128949445096;809.8542262392708;818.5988865455884;9679.274054445805;9945.651902183388;10253.436061016715;10356.741081472872;10412.947935260427;679.4055201698513;695.990813320687;808.1554775259867;809.4697972827807;819.4182130687279;4478.731276123378;4579.610733087658;4650.534946505322;4738;4903.632227582945;61585;62140.689296553224;64063;64190;64873;53602.46397536053;55105.72447137819;55749;55956;56440;48345;48762;49690.75154624276;50057.49942500601;50653.74673126611;10172.745681358025;10233.641822536176;10251.979122443254;10265.2301077418;10276.280660353703;35829.1042723934;36849.26301474009;37359;37527.81236093802;37834;43303.535717141924;46822.76588617035;47873;48142;48250.75874620605;12782.105252632598;12810.103292769416;12948.223106613634;12964.286964217059;13048.086633935533;13877.708824870359;13903.374348154559;13912.860871391156;14133.15201087938;14246.823956442731;11605.709857253298;13757.38091785852;13860.722785544434;13866.237356945465;14178.787318190029;12247.571335003233;12256.938715306367;12549.309787961385;12674.302913339854;12826.294553799631;12821;12881.162724422882;13196.934015329862;13516.797248041485;13550.864491355354;10848.728781780203;11090.279131856403;11116.916722649414;11159.330440173615;11237.269577477438;11352.432378381212;11534.769304614028;11737;11788.23376480526;11801 22:36:55 INFO - PROCESS | 4285 | __end_tp_report 22:36:55 INFO - PROCESS | 4285 | __start_cc_report 22:36:55 INFO - PROCESS | 4285 | _x_x_mozilla_cycle_collect,888 22:36:55 INFO - PROCESS | 4285 | __end_cc_report 22:36:55 INFO - PROCESS | 4285 | __startTimestamp1465105015695__endTimestamp 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | ------- Summary: start ------- 22:36:55 INFO - PROCESS | 4285 | Number of tests: 6 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | [#0] dojo.html Cycles:165 Average:5531.87 Median:5738.66 stddev:611.81 (10.7%) stddev-sans-first:588.52 22:36:55 INFO - PROCESS | 4285 | Values: 3318.2 3366.2 3370.0 3371.2 3411.4 5498.7 5499.0 5504.4 5508.7 5510.5 5462.2 5480.1 5496.5 5507.1 5533.1 4942.2 4958.5 5057.4 5105.3 5129.4 5005.2 5065.5 5114.1 5136.3 5145.1 5116.4 5126.7 5137.0 5139.8 5166.1 5020.4 5056.8 5069.9 5127.0 5131.7 4985.1 5039.9 5040.8 5042.7 5060.9 5416.6 5417.7 5440.5 5460.2 5482.9 5878.5 5889.3 5904.0 5904.1 5905.9 6014.9 6033.3 6038.8 6040.9 6069.1 5619.0 5623.3 5624.0 5660.8 5664.2 6023.6 6023.9 6131.4 6147.3 6147.5 5891.9 5897.2 5904.4 5907.8 5930.5 5967.7 5977.4 5977.7 5978.6 5978.7 5838.5 5893.4 5955.0 5956.3 6027.6 5834.6 5869.2 5869.4 5869.9 5909.4 4503.8 4504.0 4513.2 4521.2 4533.3 3987.9 4053.8 4060.3 4123.6 4158.5 5697.1 5700.3 5704.7 5704.8 5713.6 5981.6 5983.9 5987.4 5987.5 5989.4 5607.2 5682.4 5709.4 5772.5 5777.9 5099.4 5107.3 5124.5 5129.2 5142.5 6007.6 6182.4 6184.5 6191.6 6193.9 5921.3 5969.9 5983.7 6026.5 6026.6 6185.8 6201.1 6218.1 6221.7 6222.7 5738.7 5747.1 5878.8 5888.6 5889.6 5884.4 5939.5 5940.8 5956.1 5997.6 5760.1 5801.1 5805.3 5813.5 5815.7 5576.4 5746.9 5756.2 5808.6 5852.8 5663.9 5669.9 5676.1 5696.2 5717.4 5788.3 5792.5 5793.3 5803.9 5810.9 5675.0 5684.6 5718.6 5786.5 5829.5 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | [#1] ext.html Cycles:180 Average:51132.81 Median:3712.70 stddev:91393.21 (2461.6%) stddev-sans-first:91570.88 22:36:55 INFO - PROCESS | 4285 | Values: 615.6 658.9 678.0 678.7 758.0 44555.0 45635.1 45672.5 45689.8 45708.3 48728.3 49381.5 49410.3 49415.5 49446.8 8024.9 9004.2 9302.1 9306.6 10487.1 10565.2 10694.3 10704.5 10706.3 10707.8 10991.2 11087.7 11089.4 11091.4 11095.9 8194.3 10884.4 10936.4 11116.1 11116.7 10538.4 10606.5 10632.4 10658.4 10661.4 44512.6 44926.8 45048.8 45146.1 45162.0 3742.5 3755.2 3822.1 4061.3 4061.7 31717.8 31728.5 32059.7 32390.2 33280.3 4358.2 4362.2 4416.4 4436.3 4517.2 236499.0 237305.0 237425.0 237774.0 238162.0 39921.6 40753.0 66471.7 66565.0 66565.0 38981.6 39032.5 39074.4 39126.4 39147.6 16139.2 16195.6 16312.7 16333.3 16366.1 13640.2 14812.1 15123.7 15135.4 15136.0 9328.2 9353.7 9357.2 9377.2 9411.0 3588.1 3596.1 3601.4 3607.5 3682.9 1068.7 1111.5 1155.1 1169.6 1201.2 33828.0 34355.9 34802.3 35009.6 35074.3 1205.9 1236.7 1243.4 1307.8 1322.4 17696.9 18017.5 18234.5 18343.4 18696.5 483452.0 488435.0 488454.0 491043.0 491267.0 64388.0 65327.7 65404.0 65486.0 65572.0 1323.8 1394.4 1426.9 1486.3 1600.7 30695.2 32176.5 32750.7 32799.0 33467.8 48300.0 48695.3 48696.0 48805.8 48811.5 250082.0 250639.0 251054.0 251327.0 252411.0 29709.0 48089.3 48203.0 48785.0 49397.5 47427.8 47619.5 48477.0 50447.2 51754.5 42190.4 42715.0 44204.1 44520.8 45076.0 41487.4 41771.4 42555.6 43057.0 43391.1 40526.8 45630.3 45843.0 47176.3 47264.3 33855.8 35820.0 36025.3 36257.5 36561.5 27150.6 27414.6 27628.2 27631.3 27747.0 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | [#2] jquery.html Cycles:180 Average:32251.87 Median:3247.39 stddev:87937.44 (2707.9%) stddev-sans-first:88151.68 22:36:55 INFO - PROCESS | 4285 | Values: 435.2 440.9 441.0 441.6 452.8 20218.1 20287.4 21043.9 21053.8 21060.4 20520.9 20569.3 20692.5 20862.4 20892.9 5530.8 5743.4 5785.5 5789.0 5791.1 4225.2 4829.4 5910.9 6139.7 6466.6 6465.9 6492.9 6508.8 6518.1 6520.3 6493.4 6860.6 7204.1 7223.6 7223.7 6894.4 6908.3 6961.9 6999.7 7025.0 19687.6 25345.7 25930.0 26922.6 26952.9 2823.1 2841.4 3096.0 3165.3 3329.5 15678.6 16404.2 16411.6 16643.6 16974.2 2218.5 2276.1 2289.1 2291.9 2318.7 96589.0 97667.5 99639.5 101396.5 101604.0 18315.5 18850.8 19155.0 19256.2 19540.7 35201.8 35330.0 35418.5 35859.8 35896.0 8196.5 8592.8 8648.3 9220.9 9315.8 9126.5 9143.1 9207.2 9208.7 9256.5 5051.4 5118.8 5193.7 5203.8 5240.1 1892.0 2015.3 2071.5 2161.8 2182.8 998.7 1018.5 1021.7 1173.9 1174.6 17965.6 18541.9 19544.8 19550.3 19953.0 1072.4 1076.4 1094.3 1120.4 1136.6 10788.8 11130.4 11216.4 11221.2 11323.7 522552.0 528303.0 529641.0 535103.0 547858.0 94686.0 98407.5 98598.0 98940.5 99739.5 1108.1 1133.4 1147.3 1163.9 1164.9 16982.9 17156.6 17186.7 17254.9 17318.7 40204.2 41396.0 42230.8 42530.6 42645.0 60671.0 60947.7 60980.1 61246.1 62246.0 8598.8 8864.3 8902.6 8917.6 9069.2 9777.9 9851.8 10004.6 13725.5 13818.4 6194.0 9349.0 10714.4 12180.7 12381.8 11948.1 12135.6 12257.3 12461.5 12470.8 12229.3 12258.1 12432.6 12570.8 12723.6 9256.1 9331.3 9379.6 9512.9 9579.1 12288.4 12498.2 12524.5 12559.8 12608.5 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | [#3] mootools.html Cycles:180 Average:5871.48 Median:5020.39 stddev:3246.26 (64.7%) stddev-sans-first:3238.11 22:36:55 INFO - PROCESS | 4285 | Values: 1418.6 1588.3 1620.2 1860.2 1930.9 8551.8 8754.7 8760.5 8766.5 8766.5 8295.2 8444.5 8483.7 8485.2 8485.7 2144.4 2410.5 2440.0 2461.4 2461.6 2372.2 2376.9 2380.8 2382.5 2384.5 2418.9 2436.9 2498.0 2502.1 2504.1 2107.6 2415.4 2427.7 2460.0 2470.7 2330.8 2366.9 2423.6 2426.7 2428.5 8690.5 8705.6 8740.3 8740.7 8742.5 5393.4 5484.5 5492.1 5502.4 5506.5 3517.8 3590.0 3616.3 3742.1 3761.6 1125.4 1126.5 1134.5 1147.0 1248.4 12579.4 12652.2 12680.6 12688.6 12708.2 4915.3 4986.1 5000.0 5022.9 5046.3 10784.1 10820.0 10837.7 10849.3 10857.8 5707.6 5709.7 5710.4 5712.6 5728.2 4506.7 5017.8 5046.1 5061.8 5116.9 4242.9 4243.6 4299.0 4349.3 4351.5 1347.7 1391.4 1395.9 1396.9 1405.1 935.7 1020.2 1032.3 1036.4 1069.7 5859.2 6035.1 6092.8 6171.7 6217.8 965.7 1107.5 1137.7 1170.4 1170.9 3849.8 3855.8 4003.2 4071.2 4204.6 10497.2 10710.6 10789.5 10886.3 10904.8 10487.2 10693.4 10858.8 10878.7 10881.9 8412.3 8551.0 8552.1 8605.8 8608.7 5866.4 5874.2 5900.4 5998.8 6016.7 9653.2 9686.9 9800.6 9941.6 9969.0 9894.4 9900.2 10067.1 10110.0 10163.3 6759.8 7001.0 7004.0 7445.6 7651.1 8201.6 8368.8 8387.3 8402.2 8408.1 7547.1 7680.5 7723.0 7771.7 7800.8 7541.2 7589.3 7630.8 7692.0 7710.1 7014.5 7089.8 7096.3 7182.5 7239.0 5358.4 5370.4 5458.3 5529.9 5553.3 5043.3 5144.2 5189.4 5320.6 5368.8 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | [#4] prototype.html Cycles:180 Average:5574.41 Median:6429.15 stddev:1657.44 (25.8%) stddev-sans-first:1615.25 22:36:55 INFO - PROCESS | 4285 | Values: 361.7 370.2 372.1 377.8 380.2 6131.2 6204.2 6234.3 6251.4 6294.3 6062.9 6155.8 6167.7 6232.4 6275.6 4830.4 4833.1 4846.4 4847.4 4847.7 4595.1 4712.2 4791.7 4843.5 4845.7 4747.5 4749.3 4765.4 4772.8 4779.1 4742.7 4743.0 4749.1 4759.0 4765.5 4262.7 4271.3 4275.9 4280.6 4284.9 5995.9 6008.7 6012.6 6021.2 6026.5 5426.3 5609.3 5627.2 5627.9 5634.2 6575.9 6605.0 6621.7 6638.6 6643.1 4772.9 4779.3 4779.8 4784.9 4786.2 6773.7 6852.9 6930.3 6957.5 6961.6 4710.8 4727.2 4739.9 4740.8 4743.8 4773.5 4792.7 4794.0 4795.7 4801.0 5523.9 5530.8 5535.1 5539.6 5543.3 6652.2 6659.3 6694.0 6725.9 6737.5 1659.8 1702.5 1736.4 1738.1 1748.4 1158.8 1177.4 1186.7 1188.1 1189.8 6428.6 6442.7 6449.1 6453.4 6457.6 6809.3 6818.4 6819.4 6822.5 6822.7 6429.7 6446.3 6446.7 6448.7 6462.9 3653.3 3656.7 3686.3 3686.8 3688.9 6857.7 7026.6 7059.5 7083.0 7086.9 6926.8 6946.8 6951.2 6953.3 6964.6 7102.2 7104.5 7110.5 7116.5 7123.9 6788.6 6796.4 6803.7 6804.6 6813.8 6562.4 6678.0 6723.9 6731.3 6743.2 7090.1 7097.1 7105.9 7130.9 7136.5 4893.0 4895.9 4903.6 4905.2 4907.1 6564.7 6718.5 6769.8 6770.5 6772.8 6707.2 6724.5 6738.7 6743.1 6744.6 6693.1 6699.2 6701.2 6708.9 6714.8 6543.9 6578.5 6583.2 6588.8 6597.7 6647.1 6696.9 6740.9 6750.5 6811.3 6503.6 6529.6 6546.7 6565.3 6569.0 22:36:55 INFO - PROCESS | 4285 | 22:36:55 INFO - PROCESS | 4285 | [#5] yui.html Cycles:180 Average:15433.88 Median:4615.07 stddev:17291.49 (374.7%) stddev-sans-first:17305.67 22:36:55 INFO - PROCESS | 4285 | Values: 925.6 935.6 942.1 947.6 970.7 15106.7 15500.3 15599.1 15619.4 15631.8 14477.3 14574.6 14579.7 14610.0 14630.9 741.6 912.1 935.9 942.9 946.5 925.5 932.1 934.6 934.9 935.8 994.0 995.1 995.5 996.4 997.1 808.6 971.4 986.0 990.7 991.0 724.8 725.3 725.4 725.8 726.2 14651.6 14724.7 14743.8 14773.6 14779.7 15018.9 15034.3 15377.0 15447.4 15509.7 12050.2 12094.3 12265.2 12336.1 12402.4 4682.7 4699.7 4708.5 4711.6 4782.8 54514.5 54862.7 55116.7 55180.0 56133.0 11269.5 11417.4 11490.1 11523.9 11683.1 20603.8 20612.4 20854.4 20904.0 20908.4 8605.9 8783.5 8814.7 8833.9 9001.4 4899.7 5006.8 5011.7 5030.8 5034.2 3584.9 3644.7 3648.5 3663.8 3667.0 664.2 674.9 685.9 687.1 742.3 732.4 739.0 799.1 809.9 818.6 9679.3 9945.7 10253.4 10356.7 10412.9 679.4 696.0 808.2 809.5 819.4 4478.7 4579.6 4650.5 4738.0 4903.6 61585.0 62140.7 64063.0 64190.0 64873.0 53602.5 55105.7 55749.0 55956.0 56440.0 48345.0 48762.0 49690.8 50057.5 50653.7 10172.7 10233.6 10252.0 10265.2 10276.3 35829.1 36849.3 37359.0 37527.8 37834.0 43303.5 46822.8 47873.0 48142.0 48250.8 12782.1 12810.1 12948.2 12964.3 13048.1 13877.7 13903.4 13912.9 14133.2 14246.8 11605.7 13757.4 13860.7 13866.2 14178.8 12247.6 12256.9 12549.3 12674.3 12826.3 12821.0 12881.2 13196.9 13516.8 13550.9 10848.7 11090.3 11116.9 11159.3 11237.3 11352.4 11534.8 11737.0 11788.2 11801.0 22:36:55 INFO - PROCESS | 4285 | -------- Summary: end -------- 22:36:55 INFO - PROCESS | 4285 | 22:36:56 INFO - TEST-INFO | 4285: exit 0 22:36:56 INFO - TEST-OK | dromaeo_css | took 1110545ms 22:36:56 INFO - TEST-START | kraken 22:36:56 INFO - operating with platform_type : linux_ 22:36:56 INFO - Initialising browser for kraken test... 22:36:56 INFO - TEST-INFO | started process 4471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEVnOm3/profile http://localhost:37001/getInfo.html) 22:37:00 INFO - PROCESS | 4471 | __metrics Screen width/height:1600/1200 22:37:00 INFO - PROCESS | 4471 | colorDepth:24 22:37:00 INFO - PROCESS | 4471 | Browser inner width/height: 1024/697 22:37:00 INFO - PROCESS | 4471 | __metrics 22:37:01 ERROR - PROCESS | 4471 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:37:01 INFO - TEST-INFO | 4471: exit 0 22:37:01 INFO - Browser initialized. 22:37:01 INFO - Running cycle 1/1 for kraken test... 22:37:01 INFO - TEST-INFO | started process 4561 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEVnOm3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:37:13 INFO - PROCESS | 4561 | RSS: Main: 142290944 22:37:13 INFO - PROCESS | 4561 | 22:37:16 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37001/tests/kraken/driver.html?testName=audio-beat-detection) 22:37:16 INFO - PROCESS | 4561 | RSS: Main: 196812800 22:37:17 INFO - PROCESS | 4561 | 22:37:19 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37001/tests/kraken/driver.html?testName=audio-dft) 22:37:20 INFO - PROCESS | 4561 | RSS: Main: 181170176 22:37:20 INFO - PROCESS | 4561 | 22:37:23 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37001/tests/kraken/driver.html?testName=audio-fft) 22:37:23 INFO - PROCESS | 4561 | RSS: Main: 272506880 22:37:23 INFO - PROCESS | 4561 | 22:37:25 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37001/tests/kraken/driver.html?testName=audio-oscillator) 22:37:26 INFO - PROCESS | 4561 | RSS: Main: 177631232 22:37:26 INFO - PROCESS | 4561 | 22:37:28 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37001/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:37:29 INFO - PROCESS | 4561 | RSS: Main: 175607808 22:37:29 INFO - PROCESS | 4561 | 22:37:32 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37001/tests/kraken/driver.html?testName=imaging-darkroom) 22:37:32 INFO - PROCESS | 4561 | RSS: Main: 211296256 22:37:32 INFO - PROCESS | 4561 | 22:37:36 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37001/tests/kraken/driver.html?testName=imaging-desaturate) 22:37:36 INFO - PROCESS | 4561 | RSS: Main: 211492864 22:37:36 INFO - PROCESS | 4561 | 22:37:39 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37001/tests/kraken/driver.html?testName=json-parse-financial) 22:37:39 INFO - PROCESS | 4561 | RSS: Main: 211726336 22:37:39 INFO - PROCESS | 4561 | 22:37:41 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37001/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:42 INFO - PROCESS | 4561 | RSS: Main: 191598592 22:37:42 INFO - PROCESS | 4561 | 22:37:43 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:37:44 INFO - PROCESS | 4561 | RSS: Main: 197242880 22:37:44 INFO - PROCESS | 4561 | 22:37:46 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:37:47 INFO - PROCESS | 4561 | RSS: Main: 205873152 22:37:47 INFO - PROCESS | 4561 | 22:37:49 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:37:49 INFO - PROCESS | 4561 | RSS: Main: 211783680 22:37:49 INFO - PROCESS | 4561 | 22:37:52 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:37:53 INFO - PROCESS | 4561 | RSS: Main: 209108992 22:37:53 INFO - PROCESS | 4561 | 22:37:54 INFO - PROCESS | 4561 | Cycle 1(1): loaded http://localhost:37001/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37001/tests/kraken/driver.html?testName=ai-astar) 22:37:55 INFO - PROCESS | 4561 | RSS: Main: 272584704 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | __start_tp_report 22:37:55 INFO - PROCESS | 4561 | _x_x_mozilla_page_load 22:37:55 INFO - PROCESS | 4561 | _x_x_mozilla_page_load_details 22:37:55 INFO - PROCESS | 4561 | |i|pagename|runs| 22:37:55 INFO - PROCESS | 4561 | |0;ai-astar;110;110;111;111;112;113;105;109;110;112 22:37:55 INFO - PROCESS | 4561 | |1;audio-beat-detection;167;127;159;185;126;167;155;134;200;127 22:37:55 INFO - PROCESS | 4561 | |2;audio-dft;164;160;162;160;161;161;163;172;161;162 22:37:55 INFO - PROCESS | 4561 | |3;audio-fft;73;77;75;181;73;73;75;74;156;76 22:37:55 INFO - PROCESS | 4561 | |4;audio-oscillator;92;92;155;92;92;92;150;93;93;144 22:37:55 INFO - PROCESS | 4561 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;116;116;116 22:37:55 INFO - PROCESS | 4561 | |6;imaging-darkroom;144;145;145;145;146;145;145;145;146;145 22:37:55 INFO - PROCESS | 4561 | |7;imaging-desaturate;95;95;96;95;95;95;95;94;95;94 22:37:55 INFO - PROCESS | 4561 | |8;json-parse-financial;56;54;55;56;55;54;54;76;55;53 22:37:55 INFO - PROCESS | 4561 | |9;json-stringify-tinderbox;57;58;57;57;58;57;57;57;57;58 22:37:55 INFO - PROCESS | 4561 | |10;stanford-crypto-aes;72;74;74;73;73;74;74;74;73;75 22:37:55 INFO - PROCESS | 4561 | |11;stanford-crypto-ccm;121;113;115;114;115;114;112;114;126;115 22:37:55 INFO - PROCESS | 4561 | |12;stanford-crypto-pbkdf2;159;164;156;158;168;160;157;166;159;161 22:37:55 INFO - PROCESS | 4561 | |13;stanford-crypto-sha256-iterative;68;67;68;69;67;66;67;68;68;65 22:37:55 INFO - PROCESS | 4561 | __end_tp_report 22:37:55 INFO - PROCESS | 4561 | __start_cc_report 22:37:55 INFO - PROCESS | 4561 | _x_x_mozilla_cycle_collect,1577 22:37:55 INFO - PROCESS | 4561 | __end_cc_report 22:37:55 INFO - PROCESS | 4561 | __startTimestamp1465105075211__endTimestamp 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | ------- Summary: start ------- 22:37:55 INFO - PROCESS | 4561 | Number of tests: 14 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.21 (2.0%) stddev-sans-first:2.35 22:37:55 INFO - PROCESS | 4561 | Values: 110.0 110.0 111.0 111.0 112.0 113.0 105.0 109.0 110.0 112.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#1] audio-beat-detection Cycles:10 Average:154.70 Median:163.00 stddev:25.99 (15.9%) stddev-sans-first:27.18 22:37:55 INFO - PROCESS | 4561 | Values: 167.0 127.0 159.0 185.0 126.0 167.0 155.0 134.0 200.0 127.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#2] audio-dft Cycles:10 Average:162.60 Median:162.00 stddev:3.53 (2.2%) stddev-sans-first:3.71 22:37:55 INFO - PROCESS | 4561 | Values: 164.0 160.0 162.0 160.0 161.0 161.0 163.0 172.0 161.0 162.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#3] audio-fft Cycles:10 Average:93.30 Median:74.50 stddev:40.09 (53.8%) stddev-sans-first:41.85 22:37:55 INFO - PROCESS | 4561 | Values: 73.0 77.0 75.0 181.0 73.0 73.0 75.0 74.0 156.0 76.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#4] audio-oscillator Cycles:10 Average:109.50 Median:92.00 stddev:27.84 (30.3%) stddev-sans-first:28.80 22:37:55 INFO - PROCESS | 4561 | Values: 92.0 92.0 155.0 92.0 92.0 92.0 150.0 93.0 93.0 144.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 22:37:55 INFO - PROCESS | 4561 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.44 22:37:55 INFO - PROCESS | 4561 | Values: 144.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:37:55 INFO - PROCESS | 4561 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:55.00 stddev:6.81 (12.4%) stddev-sans-first:7.22 22:37:55 INFO - PROCESS | 4561 | Values: 56.0 54.0 55.0 56.0 55.0 54.0 54.0 76.0 55.0 53.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#9] json-stringify-tinderbox Cycles:10 Average:57.30 Median:57.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 22:37:55 INFO - PROCESS | 4561 | Values: 57.0 58.0 57.0 57.0 58.0 57.0 57.0 57.0 57.0 58.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:74.00 stddev:0.84 (1.1%) stddev-sans-first:0.67 22:37:55 INFO - PROCESS | 4561 | Values: 72.0 74.0 74.0 73.0 73.0 74.0 74.0 74.0 73.0 75.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#11] stanford-crypto-ccm Cycles:10 Average:115.90 Median:115.00 stddev:4.28 (3.7%) stddev-sans-first:4.12 22:37:55 INFO - PROCESS | 4561 | Values: 121.0 113.0 115.0 114.0 115.0 114.0 112.0 114.0 126.0 115.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:160.50 stddev:3.97 (2.5%) stddev-sans-first:4.15 22:37:55 INFO - PROCESS | 4561 | Values: 159.0 164.0 156.0 158.0 168.0 160.0 157.0 166.0 159.0 161.0 22:37:55 INFO - PROCESS | 4561 | 22:37:55 INFO - PROCESS | 4561 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 22:37:55 INFO - PROCESS | 4561 | Values: 68.0 67.0 68.0 69.0 67.0 66.0 67.0 68.0 68.0 65.0 22:37:55 INFO - PROCESS | 4561 | -------- Summary: end -------- 22:37:55 INFO - PROCESS | 4561 | 22:37:56 INFO - TEST-INFO | 4561: exit 0 22:37:56 INFO - TEST-OK | kraken | took 60014ms 22:37:56 INFO - SUITE-END | took 1170s 22:37:56 INFO - Completed test suite (00:19:30) 22:37:56 INFO - javascript benchmark 22:37:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.236805534727, 3366.2257680733414, 3370.039538731462, 3371.2246183377815, 3411.3518431498014, 5498.697571633558, 5499.0, 5504.394516603171, 5508.669479831213, 5510.476504732054, 5462.20797984293, 5480.095784195613, 5496.477834605706, 5507.118860982244, 5533.0870406383, 4942.18453955097, 4958.528939750718, 5057.41839688435, 5105.259737338084, 5129.358830146231, 5005.224190250517, 5065.493450654942, 5114.079465696173, 5136.332276804011, 5145.073886700392, 5116.386033675945, 5126.692398456086, 5137.023965446572, 5139.794408223666, 5166.095933211692, 5020.422651395063, 5056.84829455117, 5069.873253168664, 5127.02586508564, 5131.692098474085, 4985.077760614262, 5039.924401133986, 5040.79836806531, 5042.697438153723, 5060.873478163038, 5416.647917885325, 5417.674939503642, 5440.510354068113, 5460.208269800856, 5482.91775623366, 5878.470937615635, 5889.293284805808, 5903.970480147572, 5904.084866845646, 5905.940940590582, 6014.879702405928, 6033.336333003366, 6038.788642397539, 6040.909386359208, 6069.119977603223, 5619.044762390404, 5623.325200975909, 5624.0439125348785, 5660.830175094754, 5664.150377443417, 6023.578349515492, 6023.909641355295, 6131.417515336036, 6147.323794382614, 6147.477464415485, 5891.882162356815, 5897.174395584621, 5904.409559044061, 5907.763689452374, 5930.466258036798, 5967.731452084667, 5977.402259773988, 5977.701114944322, 5978.581499295007, 5978.701064946736, 5838.474537291664, 5893.44012318829, 5955.0, 5956.315023772235, 6027.578069535178, 5834.649921004753, 5869.236999190163, 5869.354371019184, 5869.853253668693, 5909.3795151508475, 4503.7522936238165, 4503.977480112644, 4513.165064463019, 4521.231390663595, 4533.342665313511, 3987.900302492461, 4053.817578208929, 4060.3300455425115, 4123.60825721556, 4158.500979882433, 5697.145428185722, 5700.315962084576, 5704.686242256633, 5704.828855134353, 5713.571482138773, 5981.641101533922, 5983.880322393615, 5987.43119403656, 5987.491063259672, 5989.431004054608, 5607.214989901417, 5682.40334764852, 5709.400512946081, 5772.538196944234, 5777.88444231113, 5099.388073431138, 5107.310513080686, 5124.538791508708, 5129.179331306973, 5142.460041695643, 6007.609505382136, 6182.381761823782, 6184.536159788034, 6191.628502289877, 6193.907091393633, 5921.3190483094995, 5969.880602388015, 5983.730732117152, 6026.517878569616, 6026.578139530367, 6185.783497577563, 6201.10084037821, 6218.129461875251, 6221.720022599085, 6222.657753823402, 5738.655680659007, 5747.1379293106365, 5878.764849406147, 5888.646681199141, 5889.558283128868, 5884.35272120075, 5939.465448109518, 5940.79207227745, 5956.136360227656, 5997.640141591519, 5760.107183386582, 5801.129830525455, 5805.303363596396, 5813.476787089013, 5815.651060936358, 5576.414476479911, 5746.942530574641, 5756.194132821324, 5808.593398462067, 5852.795152169653, 5663.915041274467, 5669.886602268014, 5676.091825307931, 5696.231008813757, 5717.3996730342715, 5788.305403351625, 5792.53659707214, 5793.275840519935, 5803.8549036274435, 5810.854728631649, 5675.0, 5684.573656975661, 5718.571107166896, 5786.450287222707, 5829.504492118217], "value": 5492.356804829454, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.6213928434014, 658.9374009469101, 677.9796606101816, 678.7217240931219, 757.9880859053163, 44555.0, 45635.08729825405, 45672.543274567244, 45689.77155114228, 45708.3143752844, 48728.2690759638, 49381.50618493814, 49410.25884611725, 49415.50584494154, 49446.75276623621, 8024.879626805603, 9004.234640055603, 9302.069793020677, 9306.581203845843, 10487.056164945154, 10565.154787616972, 10694.304870183452, 10704.518296676668, 10706.250562460631, 10707.839382409271, 10991.175661825355, 11087.667369978873, 11089.445527723621, 11091.389973551451, 11095.944520277428, 8194.30348420382, 10884.401357925311, 10936.398498082603, 11116.110711143088, 11116.722081947935, 10538.420386878719, 10606.515087887701, 10632.36205827645, 10658.360498370044, 10661.360318380923, 44512.55487445116, 44926.775366123285, 45048.77475612601, 45146.097078058585, 45162.0, 3742.5134732484876, 3755.239898674472, 3822.0635944193573, 4061.3095773718537, 4061.6547593454297, 31717.84141079303, 31728.524072138935, 32059.679403206137, 32390.190245243823, 33280.33439331224, 4358.237308471005, 4362.17118747437, 4416.4258646375765, 4436.268015777393, 4517.164324599959, 236499.0, 237305.0, 237425.0, 237774.0, 238162.0, 39921.60078399237, 40753.0, 66471.66764166148, 66565.0, 66565.0, 38981.6101838978, 39032.462889912065, 39074.41388379176, 39126.41310380347, 39147.608523914394, 16139.193040347936, 16195.595110122105, 16312.673746525003, 16333.26500307489, 16366.099864507567, 13640.249786261653, 14812.111273323635, 15123.697526049638, 15135.394584216729, 15136.0, 9328.160465558132, 9353.719388418358, 9357.204637605744, 9377.156055954996, 9411.0, 3588.120910376922, 3596.1009747563107, 3601.4417765245903, 3607.494950706903, 3682.8895133146048, 1068.6793961811582, 1111.5478577576998, 1155.1163360029584, 1169.5964892112208, 1201.1531870031606, 33828.0, 34355.870930527286, 34802.30395392078, 35009.64990350115, 35074.29851403009, 1205.867354591012, 1236.6908272931767, 1243.3720970909633, 1307.7711400504872, 1322.3916998180753, 17696.91151544234, 18017.549561261072, 18234.538337324557, 18343.449696509128, 18696.53258668517, 483452.0, 488435.0, 488454.0, 491043.0, 491267.0, 64388.0, 65327.67336163384, 65404.0, 65486.0, 65572.0, 1323.7617228898891, 1394.3795011220113, 1426.8573142685857, 1486.3162945045185, 1600.6878658661683, 30695.2326191847, 32176.517352240204, 32750.672493274764, 32799.0, 33467.83266083654, 48300.0, 48695.26957095575, 48696.0, 48805.755971219914, 48811.511884880696, 250082.0, 250639.0, 251054.0, 251327.0, 252411.0, 29709.0, 48089.27866082082, 48203.0, 48785.02429951452, 49397.506024940725, 47427.762861185474, 47619.523804761506, 48477.0, 50447.24329134992, 51754.482455176476, 42190.36714449347, 42715.0, 44204.11591768082, 44520.77739611411, 45076.0, 41487.377689335284, 41771.373429399195, 42555.57444425516, 43057.0, 43391.13217735691, 40526.79736601298, 45630.31554526618, 45843.0, 47176.29235561538, 47264.2910356338, 33855.830720846236, 35820.0, 36025.27949441049, 36257.45613815848, 36561.45157822688, 27150.592741109293, 27414.5887811687, 27628.171154865388, 27631.30921726973, 27747.0], "value": 19911.368095452046, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.1731709751474, 440.8654466741469, 440.96813456511757, 441.5849101844267, 452.812082985561, 20218.09018594163, 20287.391378258657, 21043.894780526094, 21053.78946210541, 21060.36818895432, 20520.89739551304, 20569.28007519737, 20692.48268793281, 20862.374128776122, 20892.8955355223, 5530.751116199781, 5743.396943320933, 5785.508231800281, 5789.0, 5791.07342825148, 4225.239456897757, 4829.396325459317, 5910.88178236435, 6139.723712432951, 6466.612003279818, 6465.903011454832, 6492.870142597145, 6508.772192973247, 6518.120053792748, 6520.347965203489, 6493.383128602799, 6860.62787442511, 7204.063471748692, 7223.602701851396, 7223.674934627954, 6894.413974812147, 6908.309169083102, 6961.860762784716, 6999.685014174374, 7025.0, 19687.606247875105, 25345.74654253452, 25930.0, 26922.5961610576, 26952.865235673893, 2823.110720123155, 2841.4033053058674, 3095.9535606965915, 3165.303633200715, 3329.467285234375, 15678.608034799106, 16404.17979101052, 16411.58971025722, 16643.583910402216, 16974.15129243533, 2218.456478162844, 2276.1350686739097, 2289.050044231642, 2291.9197828076103, 2318.6637937498967, 96589.0, 97667.51166244266, 99639.50180249124, 101396.49301753518, 101604.0, 18315.54211144732, 18850.81149188518, 19155.0, 19256.22975081009, 19540.70688939667, 35201.82399087988, 35330.0, 35418.46872296918, 35859.82070089685, 35896.0, 8196.508209507449, 8592.828143437222, 8648.308135349283, 9220.8616870747, 9315.813683726423, 9126.45241285525, 9143.085691430937, 9207.17135457812, 9208.677696280733, 9256.490893000815, 5051.444341122474, 5118.820841270538, 5193.714345710947, 5203.7918483261, 5240.109181439165, 1892.0256068124927, 2015.2845739762467, 2071.523549583574, 2161.8378621603442, 2182.792634699733, 998.7453199434098, 1018.4805749067881, 1021.6934919524113, 1173.8650055243586, 1174.6123779152858, 17965.640687186184, 18541.856865002163, 19544.80455195458, 19550.31573894907, 19953.0, 1072.4101744040902, 1076.4187338837096, 1094.3051162511863, 1120.3613940054254, 1136.6303604156972, 10788.838167427495, 11130.38782866934, 11216.383098929637, 11221.214514984036, 11323.660290191307, 522552.0, 528303.0, 529641.0, 535103.0, 547858.0, 94686.0, 98407.50796246117, 98598.0, 98940.50529747449, 99739.50130249302, 1108.0636861851722, 1133.393634405589, 1147.300146910384, 1163.9243449175947, 1164.8602167739925, 16982.915085424494, 17156.571085722957, 17186.742198866777, 17254.913725431794, 17318.740218896473, 40204.1959160821, 41396.0, 42230.78884605557, 42530.57469425266, 42645.0, 60671.0, 60947.69526152341, 60980.08529871967, 61246.08130877951, 62246.0, 8598.82802343962, 8864.290856731575, 8902.59938302791, 8917.643294268157, 9069.22911552499, 9777.853332200166, 9851.802963940825, 10004.599816007278, 13725.450981961008, 13818.447262109403, 6193.969030154821, 9348.95325523368, 10714.357138571711, 12180.695482613006, 12381.75236495283, 11948.095971605271, 12135.575254866037, 12257.325847078497, 12461.501539938663, 12470.750584988431, 12229.3273869938, 12258.080643951918, 12432.627021189379, 12570.811437828257, 12723.618291451272, 9256.120668536478, 9331.253499720142, 9379.577918993798, 9512.857307140537, 9579.089986451276, 12288.447019883948, 12498.187617804813, 12524.499020039097, 12559.811602826148, 12608.49566017349], "value": 10382.177787079154, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.588609303302, 1588.3249618911962, 1620.206099011484, 1860.2280053777683, 1930.9227630894766, 8551.828963420729, 8754.737357879274, 8760.518171500566, 8766.474011559303, 8766.517841518722, 8295.170482951702, 8444.4511106778, 8483.66065357385, 8485.151484851527, 8485.660573577064, 2144.3781303422065, 2410.505846301513, 2439.963400548989, 2461.4215659320043, 2461.5692253855505, 2372.1697405907967, 2376.9049238030457, 2380.797632201265, 2382.523495300938, 2384.4873352229265, 2418.8669623170717, 2436.8537887726793, 2498.038255271724, 2502.1492692484553, 2504.0860086068637, 2107.6100921329526, 2415.3599296186526, 2427.6965379327585, 2459.9754002460104, 2470.7035155781423, 2330.7669233076726, 2366.9053237870467, 2423.551642946043, 2426.745191754858, 2428.5264373447185, 8690.522021288827, 8705.608247628808, 8740.300775937973, 8740.694075707319, 8742.519161446118, 5393.352797664305, 5484.451554844523, 5492.066348720727, 5502.422245664227, 5506.531944784698, 3517.82410879455, 3589.982050089759, 3616.2948225096115, 3742.0831896185337, 3761.6238376162437, 1125.4260327233087, 1126.4705588373452, 1134.4781400555755, 1146.9541218351255, 1248.3695733654538, 12579.433925473284, 12652.240865548096, 12680.556180533637, 12688.555900543437, 12708.237505749592, 4915.262710593368, 4986.077575648516, 4999.975000125049, 5022.949770502249, 5046.34397528319, 10784.08335291509, 10820.0, 10837.67486975392, 10849.294795838245, 10857.83713244286, 5707.600467967285, 5709.714514274269, 5710.428957104339, 5712.571557133231, 5728.198052272602, 4506.729596224237, 5017.82437614689, 5046.1169295373165, 5061.848144555669, 5116.872078198075, 4242.936355954663, 4243.575642435793, 4298.957010429918, 4349.304111342233, 4351.4995775485595, 1347.6832944258092, 1391.4086513231837, 1395.8813500852339, 1396.8882489400823, 1405.107756574562, 935.7473482159779, 1020.2042406922612, 1032.3341444768232, 1036.3729943384294, 1069.7165251208437, 5859.2382990211, 6035.1249068885645, 6092.786752463642, 6171.660558669227, 6217.844553886099, 965.7344230336573, 1107.4739498738195, 1137.6985098948785, 1170.449888552379, 1170.8946194842335, 3849.7882616456363, 3855.845766169378, 4003.2393845169327, 4071.1654110907193, 4204.600562946576, 10497.212709046851, 10710.571577136983, 10789.514471848786, 10886.346819190874, 10904.781904362027, 10487.213458990454, 10693.41186234749, 10858.782824343627, 10878.72803179911, 10881.891181088246, 8412.284955778827, 8550.957245213734, 8552.102029286836, 8605.784855378666, 8608.741737747878, 5866.442687944638, 5874.236349274567, 5900.350961394329, 5998.770252098222, 6016.699165041643, 9653.227741780782, 9686.854697179408, 9800.607975680892, 9941.602335906482, 9969.0, 9894.406335619598, 9900.158486528726, 10067.1442927352, 10110.039546243095, 10163.339382940085, 6759.831004224934, 7000.964995174992, 7004.019437278683, 7445.553266804009, 7651.120121186135, 8201.589920503831, 8368.83262334762, 8387.329013679013, 8402.24379805796, 8408.075111737826, 7547.132079810892, 7680.500767450098, 7723.0, 7771.650275737492, 7800.765977020698, 7541.170471248049, 7589.316961473494, 7630.847383052197, 7692.0, 7710.074791025113, 7014.5440546364325, 7089.7873063808165, 7096.254893236136, 7182.497225194186, 7239.022731931333, 5358.356997160366, 5370.436104209002, 5458.2904222450825, 5529.917051244315, 5553.250311207935, 5043.268726034631, 5144.231961703411, 5189.428058144757, 5320.600954928474, 5368.8389348319615], "value": 4766.860201964543, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.6925613228756, 370.16527729968925, 372.099519163624, 377.80165413158073, 380.1750202061528, 6131.202943617323, 6204.193454850869, 6234.251889773233, 6251.374862513758, 6294.307626161119, 6062.939370606293, 6155.768846230761, 6167.691615419233, 6232.407921247485, 6275.5920865143735, 4830.444498882621, 4833.10587541306, 4846.41842978843, 4847.442544107437, 4847.660663753539, 4595.103954728829, 4712.151812673735, 4791.688540244873, 4843.539863712942, 4845.733484658343, 4747.4540427850725, 4749.287606858981, 4765.380500534926, 4772.785224664881, 4779.115863565252, 4742.691725037878, 4742.95257047432, 4749.050189961987, 4758.976205118987, 4765.499622539618, 4262.658987281041, 4271.252530807096, 4275.850345237902, 4280.550542193056, 4284.871453856358, 5995.910061349084, 6008.729607167688, 6012.5791194616395, 6021.187139736124, 6026.548008899306, 5426.294581704393, 5609.270794796691, 5627.155926611, 5627.943720562783, 5634.154876768476, 6575.868482630321, 6605.0, 6621.668916554153, 6638.634875081869, 6643.1363922690225, 4772.904541909177, 4779.259214821674, 4779.832705855279, 4784.8994731212015, 4786.21027530457, 6773.695183716744, 6852.897206541856, 6930.30696930308, 6957.547759395623, 6961.582305061713, 4710.8351207707565, 4727.172744769651, 4739.905201895943, 4740.834070807505, 4743.83396581118, 4773.451053128865, 4792.664513484093, 4794.0, 4795.664303498722, 4801.039792041536, 5523.917141242885, 5530.751116199781, 5535.059039963216, 5539.639923404966, 5543.251661025791, 6652.201735791736, 6659.300773418819, 6693.9918024592425, 6725.932740672629, 6737.52837301394, 1659.7593348964574, 1702.4977631598708, 1736.4096207289583, 1738.1309345327336, 1748.361847925498, 1158.7798318319492, 1177.375990724917, 1186.655869797749, 1188.1445359341433, 1189.7679952409196, 6428.55000149985, 6442.710078046499, 6449.097126402307, 6453.386928241904, 6457.61254324742, 6809.250982391933, 6818.352256535622, 6819.352161544645, 6822.488313376417, 6822.692978815965, 6429.742810287629, 6446.323136070645, 6446.7421303148285, 6448.709808058648, 6462.903056454157, 3653.324135035027, 3656.670899619047, 3686.299603075407, 3686.760360576554, 3688.944665830015, 6857.725690972405, 7026.648667566601, 7059.505834591632, 7083.0437890885105, 7086.893696594555, 6926.826829329307, 6946.791596252019, 6951.165860096719, 6953.269906659829, 6964.616946068015, 7102.183248926336, 7104.502684812013, 7110.466714996396, 7116.537425067354, 7123.928760712534, 6788.59268443895, 6796.35434633709, 6803.659817009228, 6804.591724496546, 6813.829654258683, 6562.409383155539, 6678.031685405686, 6723.865522689616, 6731.32686731323, 6743.1908171019795, 7090.07828982229, 7097.077379940574, 7105.928940710527, 7130.928690713234, 7136.4647651427385, 4892.975535122301, 4895.853124406273, 4903.5586797188425, 4905.190643543918, 4907.092187945242, 6564.671766411756, 6718.462522998246, 6769.830754230987, 6770.4922130839395, 6772.762953296611, 6707.228668702966, 6724.495662825406, 6738.719643267764, 6743.055972163999, 6744.595324280559, 6693.129893114059, 6699.162604674416, 6701.229358623821, 6708.8993665094085, 6714.83212919681, 6543.901841472287, 6578.5395022347975, 6583.242927063257, 6588.769393071219, 6597.670116494252, 6647.069410282467, 6696.899546506904, 6740.865182696417, 6750.5274630775375, 6811.284815094542, 6503.6422996733745, 6529.608223506605, 6546.738130474728, 6565.277819439953, 6568.967155164193], "value": 5044.843983066571, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [925.5881132895862, 935.6062300835681, 942.100294219021, 947.6493697331988, 970.6505657963132, 15106.697866042687, 15500.302486388111, 15599.06405615661, 15619.375224991016, 15631.765523517142, 14477.348519316629, 14574.635634109152, 14579.708405831878, 14610.0, 14630.926845365784, 741.6291854072977, 912.0788004115824, 935.9064093590621, 942.9245660347156, 946.4983558713893, 925.4586067150725, 932.114491233331, 934.6401635370397, 934.9485778282192, 935.7847695030147, 993.9652112176075, 995.0944640377259, 995.5470261031213, 996.3922007575374, 997.1175509673955, 808.6361137488125, 971.4268581536892, 985.9704208873745, 990.6928852055813, 990.9653162139326, 724.7572063358781, 725.3435640745134, 725.361681720083, 725.760499035317, 726.2483329753733, 14651.63370915725, 14724.705505889822, 14743.77884331736, 14773.556793296111, 14779.704405911929, 15018.924905375403, 15034.323455444532, 15377.0, 15447.382104715798, 15509.689806203925, 12050.216735912138, 12094.274343539328, 12265.202761820454, 12336.136470446983, 12402.379881005914, 4682.719036857799, 4699.694519856198, 4708.529147085298, 4711.552402521755, 4782.760861956923, 54514.45485545133, 54862.72568637131, 55116.72441637806, 55180.0, 56133.0, 11269.492872820743, 11417.37204453763, 11490.138239631893, 11523.884761152281, 11683.065354771597, 20603.79396206049, 20612.381628551168, 20854.37436876896, 20904.0, 20908.37274881756, 8605.913940860637, 8783.472991620396, 8814.691485797966, 8833.911660883437, 9001.369904106781, 4899.730514821719, 5006.799728010838, 5011.674241174312, 5030.773615187325, 5034.194528875436, 3584.946225806615, 3644.6902013329163, 3648.4892115103908, 3663.780173189564, 3667.0, 664.1631544254275, 674.8886433738477, 685.873113474009, 687.1410736579276, 742.3393180069743, 732.3701616609711, 738.9630518474055, 799.128949445096, 809.8542262392708, 818.5988865455884, 9679.274054445805, 9945.651902183388, 10253.436061016715, 10356.741081472872, 10412.947935260427, 679.4055201698513, 695.990813320687, 808.1554775259867, 809.4697972827807, 819.4182130687279, 4478.731276123378, 4579.610733087658, 4650.534946505322, 4738.0, 4903.632227582945, 61585.0, 62140.689296553224, 64063.0, 64190.0, 64873.0, 53602.46397536053, 55105.72447137819, 55749.0, 55956.0, 56440.0, 48345.0, 48762.0, 49690.75154624276, 50057.49942500601, 50653.74673126611, 10172.745681358025, 10233.641822536176, 10251.979122443254, 10265.2301077418, 10276.280660353703, 35829.1042723934, 36849.26301474009, 37359.0, 37527.81236093802, 37834.0, 43303.535717141924, 46822.76588617035, 47873.0, 48142.0, 48250.75874620605, 12782.105252632598, 12810.103292769416, 12948.223106613634, 12964.286964217059, 13048.086633935533, 13877.708824870359, 13903.374348154559, 13912.860871391156, 14133.15201087938, 14246.823956442731, 11605.709857253298, 13757.38091785852, 13860.722785544434, 13866.237356945465, 14178.787318190029, 12247.571335003233, 12256.938715306367, 12549.309787961385, 12674.302913339854, 12826.294553799631, 12821.0, 12881.162724422882, 13196.934015329862, 13516.797248041485, 13550.864491355354, 10848.728781780203, 11090.279131856403, 11116.916722649414, 11159.330440173615, 11237.269577477438, 11352.432378381212, 11534.769304614028, 11737.0, 11788.23376480526, 11801.0], "value": 7272.959795753164, "unit": "score"}], "name": "dromaeo_css", "value": 7638.2936841618975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 111.0, 111.0, 112.0, 113.0, 105.0, 109.0, 110.0, 112.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 127.0, 159.0, 185.0, 126.0, 167.0, 155.0, 134.0, 200.0, 127.0], "value": 154.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 162.0, 160.0, 161.0, 161.0, 163.0, 172.0, 161.0, 162.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 75.0, 181.0, 73.0, 73.0, 75.0, 74.0, 156.0, 76.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 155.0, 92.0, 92.0, 92.0, 150.0, 93.0, 93.0, 144.0], "value": 109.5, "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": [144.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 56.0, 55.0, 54.0, 54.0, 76.0, 55.0, 53.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 57.0, 58.0, 57.0, 57.0, 57.0, 57.0, 58.0], "value": 57.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 74.0, 73.0, 73.0, 74.0, 74.0, 74.0, 73.0, 75.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 113.0, 115.0, 114.0, 115.0, 114.0, 112.0, 114.0, 126.0, 115.0], "value": 115.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 156.0, 158.0, 168.0, 160.0, 157.0, 166.0, 159.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 69.0, 67.0, 66.0, 67.0, 68.0, 68.0, 65.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1518.6, "alertThreshold": 2.0}]} 22:37:56 INFO - Return code: 0 22:37:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:37:56 ERROR - # TBPL SUCCESS # 22:37:56 INFO - Running post-action listener: _resource_record_post_action 22:37:56 INFO - Running post-run listener: _resource_record_post_run 22:37:57 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 508256256; Read time: 0; Write time: 534896 22:37:57 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 256331776; Read time: 0; Write time: 354844 22:37:57 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 251924480; Read time: 0; Write time: 180052 22:37:57 INFO - Running post-run listener: _upload_blobber_files 22:37:57 INFO - Blob upload gear active. 22:37:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:37:57 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 22:37:57 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'] 22:37:57 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 22:37:58 INFO - (blobuploader) - INFO - Open directory for files ... 22:37:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:37:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:37:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:59 INFO - (blobuploader) - INFO - Done attempting. 22:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:38:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:38:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:38:00 INFO - (blobuploader) - INFO - Done attempting. 22:38:00 INFO - (blobuploader) - INFO - Iteration through files over. 22:38:00 INFO - Return code: 0 22:38:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:38:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:38:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fade1d40b558125c12fd02e45a9274273c64bc74b1c486043d35b3ffa79bd4bc95287f6103c117976f91960265993274041dedadbcf939b7d9138b8688938f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2d84320ad6d7dcce8282a71bbd90c8f6dcff0344bc0fbcb4b75749d5019b7d792669ce4f2957faa15d47f181b85fbc40f8534457f1112f6289a418d16e4e80a"} 22:38:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:38:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:38:00 INFO - Contents: 22:38:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fade1d40b558125c12fd02e45a9274273c64bc74b1c486043d35b3ffa79bd4bc95287f6103c117976f91960265993274041dedadbcf939b7d9138b8688938f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2d84320ad6d7dcce8282a71bbd90c8f6dcff0344bc0fbcb4b75749d5019b7d792669ce4f2957faa15d47f181b85fbc40f8534457f1112f6289a418d16e4e80a"} 22:38:00 INFO - Running post-run listener: copy_logs_to_upload_dir 22:38:00 INFO - Copying logs to upload dir... 22:38:00 INFO - mkdir: /builds/slave/test/build/upload/logs 22:38:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.672968 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-04 22:37:59.459623) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:59.462917) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337 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/7fade1d40b558125c12fd02e45a9274273c64bc74b1c486043d35b3ffa79bd4bc95287f6103c117976f91960265993274041dedadbcf939b7d9138b8688938f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2d84320ad6d7dcce8282a71bbd90c8f6dcff0344bc0fbcb4b75749d5019b7d792669ce4f2957faa15d47f181b85fbc40f8534457f1112f6289a418d16e4e80a"} build_url:https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011401 build_url: 'https://queue.taskcluster.net/v1/task/CmlK59a_SnyQaCIrN7UM5A/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fade1d40b558125c12fd02e45a9274273c64bc74b1c486043d35b3ffa79bd4bc95287f6103c117976f91960265993274041dedadbcf939b7d9138b8688938f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2d84320ad6d7dcce8282a71bbd90c8f6dcff0344bc0fbcb4b75749d5019b7d792669ce4f2957faa15d47f181b85fbc40f8534457f1112f6289a418d16e4e80a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:59.492256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:59.492607) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465102502.434701-448558337 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:37:59.577966) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:37:59.578683) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:37:59.579281) ========= ========= Total master_lag: 0.19 =========