builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1464871554.6 results: success (0) buildid: 20160602030220 builduid: 8d834d93c2314782aa56f6ea25f5cfbd revision: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:54.596961) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:54.597445) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:54.597739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:54.668603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:54.668993) ========= 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=0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-02 05:45:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.5K=0.4s 2016-06-02 05:45:55 (32.5 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.451737 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:55.141779) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:55.142117) ========= 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=0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014809 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:55.193447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 05:45:55.193992) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 --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=0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-02 05:45:55,239 truncating revision to first 12 chars 2016-06-02 05:45:55,240 Setting DEBUG logging. 2016-06-02 05:45:55,240 attempt 1/10 2016-06-02 05:45:55,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34a8be4346a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 05:45:56,489 unpacking tar archive at: mozilla-central-34a8be4346a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.578983 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 05:45:56.787561) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:56.787885) ========= script_repo_revision: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:56.788273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:56.788567) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 05:45:56.798004) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-02 05:45:56.798325) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:45:56 INFO - MultiFileLogger online at 20160602 05:45:56 in /builds/slave/test 05:45:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:45:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:45:56 INFO - {'append_to_log': False, 05:45:56 INFO - 'base_work_dir': '/builds/slave/test', 05:45:56 INFO - 'blob_upload_branch': 'Firefox', 05:45:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:45:56 INFO - 'branch': 'Firefox', 05:45:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:45:56 INFO - 'config_files': ('talos/linux_config.py',), 05:45:56 INFO - 'default_actions': ('clobber', 05:45:56 INFO - 'read-buildbot-config', 05:45:56 INFO - 'download-and-extract', 05:45:56 INFO - 'populate-webroot', 05:45:56 INFO - 'create-virtualenv', 05:45:56 INFO - 'install', 05:45:56 INFO - 'run-tests'), 05:45:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:45:56 INFO - 'download_minidump_stackwalk': True, 05:45:56 INFO - 'download_symbols': 'ondemand', 05:45:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:45:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:45:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:45:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:45:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:45:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:45:56 INFO - 'installer_path': 'installer.exe', 05:45:56 INFO - 'log_level': 'info', 05:45:56 INFO - 'log_name': 'talos', 05:45:56 INFO - 'log_to_console': True, 05:45:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:45:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:45:56 INFO - 'opt_config_files': (), 05:45:56 INFO - 'pip_index': False, 05:45:56 INFO - 'sps_profile': False, 05:45:56 INFO - 'sps_profile_interval': 0, 05:45:56 INFO - 'suite': 'dromaeojs', 05:45:56 INFO - 'system_bits': '32', 05:45:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:45:56 INFO - 'title': 'talos-linux64-ix-017', 05:45:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:45:56 INFO - 'use_talos_json': True, 05:45:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:45:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:45:56 INFO - 'work_dir': 'build'} 05:45:56 INFO - ##### 05:45:56 INFO - ##### Running clobber step. 05:45:56 INFO - ##### 05:45:56 INFO - Running pre-action listener: _resource_record_pre_action 05:45:56 INFO - Running main action method: clobber 05:45:56 INFO - rmtree: /builds/slave/test/build 05:45:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:45:57 INFO - Running post-action listener: _resource_record_post_action 05:45:57 INFO - ##### 05:45:57 INFO - ##### Finished clobber step (success) 05:45:57 INFO - ##### 05:45:57 INFO - ##### 05:45:57 INFO - ##### Running read-buildbot-config step. 05:45:57 INFO - ##### 05:45:57 INFO - Running pre-action listener: _resource_record_pre_action 05:45:57 INFO - Running main action method: read_buildbot_config 05:45:57 INFO - Using buildbot properties: 05:45:57 INFO - { 05:45:57 INFO - "project": "", 05:45:57 INFO - "product": "firefox", 05:45:57 INFO - "script_repo_revision": "production", 05:45:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:45:57 INFO - "repository": "", 05:45:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:45:57 INFO - "buildid": "20160602030220", 05:45:57 INFO - "stage_platform": "linux64-pgo", 05:45:57 INFO - "basedir": "/builds/slave/test", 05:45:57 INFO - "nightly_build": "True", 05:45:57 INFO - "buildnumber": 317, 05:45:57 INFO - "slavename": "talos-linux64-ix-017", 05:45:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:45:57 INFO - "platform": "ubuntu64_hw", 05:45:57 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:45:57 INFO - "branch": "mozilla-central", 05:45:57 INFO - "repo_path": "mozilla-central", 05:45:57 INFO - "revision": "34a8be4346a9231e472fc36b1d7c0531e0fbf7c5", 05:45:57 INFO - "pgo_build": "True", 05:45:57 INFO - "builduid": "8d834d93c2314782aa56f6ea25f5cfbd", 05:45:57 INFO - "slavebuilddir": "test" 05:45:57 INFO - } 05:45:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:45:57 INFO - Running post-action listener: _resource_record_post_action 05:45:57 INFO - ##### 05:45:57 INFO - ##### Finished read-buildbot-config step (success) 05:45:57 INFO - ##### 05:45:57 INFO - ##### 05:45:57 INFO - ##### Running download-and-extract step. 05:45:57 INFO - ##### 05:45:57 INFO - Running pre-action listener: _resource_record_pre_action 05:45:57 INFO - Running main action method: download_and_extract 05:45:57 INFO - mkdir: /builds/slave/test/build/tests 05:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:57 INFO - https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:45:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:45:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:45:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:45:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:45:58 INFO - Downloaded 1361 bytes. 05:45:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:45:58 INFO - Using the following test package requirements: 05:45:58 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:45:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:45:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:45:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'jsshell-linux-x86_64.zip'], 05:45:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:45:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:45:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:45:58 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:45:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:45:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:45:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:58 INFO - https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:45:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:45:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:45:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:45:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:45:59 INFO - Downloaded 24422877 bytes. 05:45:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:45:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:46:00 INFO - Return code: 0 05:46:00 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:00 INFO - https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:46:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:46:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:46:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:46:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:46:01 INFO - Downloaded 24422877 bytes. 05:46:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:46:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:46:03 INFO - Return code: 0 05:46: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')]} 05:46:03 INFO - https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:46:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:46:04 INFO - Downloaded 10834055 bytes. 05:46:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:46:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:46:04 INFO - Return code: 0 05:46:04 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')]} 05:46:04 INFO - https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:46:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:46:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:46:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:46:05 INFO - Downloaded 59055430 bytes. 05:46:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:46:05 INFO - mkdir: /builds/slave/test/properties 05:46:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:46:05 INFO - Writing to file /builds/slave/test/properties/build_url 05:46:05 INFO - Contents: 05:46:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:46:06 INFO - Running post-action listener: _resource_record_post_action 05:46:06 INFO - Running post-action listener: set_extra_try_arguments 05:46:06 INFO - ##### 05:46:06 INFO - ##### Finished download-and-extract step (success) 05:46:06 INFO - ##### 05:46:06 INFO - ##### 05:46:06 INFO - ##### Running populate-webroot step. 05:46:06 INFO - ##### 05:46:06 INFO - Running pre-action listener: _resource_record_pre_action 05:46:06 INFO - Running main action method: populate_webroot 05:46:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:06 INFO - '--tppagecycles', 05:46:06 INFO - '7'], 05:46:06 INFO - 'tests': ['tsvgm']}}, 05:46:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:46:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:46:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:46:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['damp', 'tps']}, 05:46:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'tests': ['damp', 'tps']}, 05:46:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['dromaeo_dom']}, 05:46:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['a11yr', 05:46:06 INFO - 'ts_paint', 05:46:06 INFO - 'tpaint', 05:46:06 INFO - 'sessionrestore', 05:46:06 INFO - 'sessionrestore_no_auto_restore', 05:46:06 INFO - 'tabpaint']}, 05:46:06 INFO - 'other-e10s': {'tests': ['a11yr', 05:46:06 INFO - 'ts_paint', 05:46:06 INFO - 'tpaint', 05:46:06 INFO - 'sessionrestore', 05:46:06 INFO - 'sessionrestore_no_auto_restore', 05:46:06 INFO - 'tabpaint']}, 05:46:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:46:06 INFO - 'ts_paint', 05:46:06 INFO - 'tpaint', 05:46:06 INFO - 'sessionrestore', 05:46:06 INFO - 'sessionrestore_no_auto_restore', 05:46:06 INFO - 'tabpaint']}, 05:46:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:46:06 INFO - 'ts_paint', 05:46:06 INFO - 'tpaint', 05:46:06 INFO - 'sessionrestore', 05:46:06 INFO - 'sessionrestore_no_auto_restore', 05:46:06 INFO - 'tabpaint']}, 05:46:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['a11yr', 05:46:06 INFO - 'ts_paint', 05:46:06 INFO - 'tpaint', 05:46:06 INFO - 'sessionrestore', 05:46:06 INFO - 'sessionrestore_no_auto_restore', 05:46:06 INFO - 'tabpaint']}, 05:46:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['a11yr', 05:46:06 INFO - 'ts_paint', 05:46:06 INFO - 'tpaint', 05:46:06 INFO - 'sessionrestore', 05:46:06 INFO - 'sessionrestore_no_auto_restore', 05:46:06 INFO - 'tabpaint']}, 05:46:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['tsvgx', 05:46:06 INFO - 'tsvgr_opacity', 05:46:06 INFO - 'tart', 05:46:06 INFO - 'tscrollx', 05:46:06 INFO - 'cart']}, 05:46:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:46:06 INFO - 'tsvgr_opacity', 05:46:06 INFO - 'tart', 05:46:06 INFO - 'tscrollx', 05:46:06 INFO - 'cart']}, 05:46:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'talos_options': ['--disable-e10s'], 05:46:06 INFO - 'tests': ['tp5o']}, 05:46:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'tests': ['tp5o']}, 05:46:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'talos_options': ['--disable-e10s', 05:46:06 INFO - '--xperf_path', 05:46:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:06 INFO - 'tests': ['tp5n']}, 05:46:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:06 INFO - 'talos_options': ['--xperf_path', 05:46:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:06 INFO - 'tests': ['tp5n']}}, 05:46:06 INFO - 'talos.zip': {'path': '', 05:46:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:06 INFO - Running post-action listener: _resource_record_post_action 05:46:06 INFO - ##### 05:46:06 INFO - ##### Finished populate-webroot step (success) 05:46:06 INFO - ##### 05:46:06 INFO - ##### 05:46:06 INFO - ##### Running create-virtualenv step. 05:46:06 INFO - ##### 05:46:06 INFO - Running pre-action listener: _resource_record_pre_action 05:46:06 INFO - Running main action method: create_virtualenv 05:46:06 INFO - Creating virtualenv /builds/slave/test/build/venv 05:46:06 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 05:46:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:46:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:06 INFO - Using real prefix '/usr' 05:46:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:46:07 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:09 INFO - Installing pip.................done. 05:46:09 INFO - Return code: 0 05:46:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:09 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 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:09 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 05:46:09 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 05:46:09 INFO - Using env: {'DISPLAY': ':0', 05:46:09 INFO - 'HOME': '/home/cltbld', 05:46:09 INFO - 'LANG': 'en_US.UTF-8', 05:46:09 INFO - 'LANGUAGE': 'en_US:en', 05:46:09 INFO - 'LOGNAME': 'cltbld', 05:46:09 INFO - 'MAIL': '/var/mail/cltbld', 05:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:09 INFO - 'MOZ_NO_REMOTE': '1', 05:46:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:09 INFO - 'NO_EM_RESTART': '1', 05:46:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:09 INFO - 'PWD': '/builds/slave/test', 05:46:09 INFO - 'SHELL': '/bin/bash', 05:46:09 INFO - 'SHLVL': '1', 05:46:09 INFO - 'TERM': 'linux', 05:46:09 INFO - 'TMOUT': '86400', 05:46:09 INFO - 'USER': 'cltbld', 05:46:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:09 INFO - '_': '/tools/buildbot/bin/python'} 05:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:09 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 05:46:09 INFO - Cleaning up... 05:46:09 INFO - Return code: 0 05:46:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:46:09 INFO - Using env: {'DISPLAY': ':0', 05:46:09 INFO - 'HOME': '/home/cltbld', 05:46:09 INFO - 'LANG': 'en_US.UTF-8', 05:46:09 INFO - 'LANGUAGE': 'en_US:en', 05:46:09 INFO - 'LOGNAME': 'cltbld', 05:46:09 INFO - 'MAIL': '/var/mail/cltbld', 05:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:09 INFO - 'MOZ_NO_REMOTE': '1', 05:46:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:09 INFO - 'NO_EM_RESTART': '1', 05:46:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:09 INFO - 'PWD': '/builds/slave/test', 05:46:09 INFO - 'SHELL': '/bin/bash', 05:46:09 INFO - 'SHLVL': '1', 05:46:09 INFO - 'TERM': 'linux', 05:46:09 INFO - 'TMOUT': '86400', 05:46:09 INFO - 'USER': 'cltbld', 05:46:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:09 INFO - '_': '/tools/buildbot/bin/python'} 05:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:09 INFO - Downloading/unpacking psutil>=3.1.1 05:46:09 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 05:46:09 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 05:46:09 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 05:46:09 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 05:46:09 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 05:46:09 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 05:46:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:11 INFO - Installing collected packages: psutil 05:46:11 INFO - Running setup.py install for psutil 05:46:11 INFO - building 'psutil._psutil_linux' extension 05:46:11 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 05:46:12 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 05:46:12 INFO - building 'psutil._psutil_posix' extension 05:46:12 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 05:46:12 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 05:46:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:12 INFO - Successfully installed psutil 05:46:12 INFO - Cleaning up... 05:46:12 INFO - Return code: 0 05:46:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:46:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:46:12 INFO - Using env: {'DISPLAY': ':0', 05:46:12 INFO - 'HOME': '/home/cltbld', 05:46:12 INFO - 'LANG': 'en_US.UTF-8', 05:46:12 INFO - 'LANGUAGE': 'en_US:en', 05:46:12 INFO - 'LOGNAME': 'cltbld', 05:46:12 INFO - 'MAIL': '/var/mail/cltbld', 05:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:12 INFO - 'MOZ_NO_REMOTE': '1', 05:46:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:12 INFO - 'NO_EM_RESTART': '1', 05:46:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:12 INFO - 'PWD': '/builds/slave/test', 05:46:12 INFO - 'SHELL': '/bin/bash', 05:46:12 INFO - 'SHLVL': '1', 05:46:12 INFO - 'TERM': 'linux', 05:46:12 INFO - 'TMOUT': '86400', 05:46:12 INFO - 'USER': 'cltbld', 05:46:12 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:12 INFO - '_': '/tools/buildbot/bin/python'} 05:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:46:12 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 05:46:12 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 05:46:12 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 05:46:12 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 05:46:12 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 05:46:12 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 05:46:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:46:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:46:14 INFO - Installing collected packages: mozsystemmonitor 05:46:14 INFO - Running setup.py install for mozsystemmonitor 05:46:14 INFO - Successfully installed mozsystemmonitor 05:46:14 INFO - Cleaning up... 05:46:14 INFO - Return code: 0 05:46:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:14 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 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:14 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 05:46:14 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 05:46:14 INFO - Using env: {'DISPLAY': ':0', 05:46:14 INFO - 'HOME': '/home/cltbld', 05:46:14 INFO - 'LANG': 'en_US.UTF-8', 05:46:14 INFO - 'LANGUAGE': 'en_US:en', 05:46:14 INFO - 'LOGNAME': 'cltbld', 05:46:14 INFO - 'MAIL': '/var/mail/cltbld', 05:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:14 INFO - 'MOZ_NO_REMOTE': '1', 05:46:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:14 INFO - 'NO_EM_RESTART': '1', 05:46:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:14 INFO - 'PWD': '/builds/slave/test', 05:46:14 INFO - 'SHELL': '/bin/bash', 05:46:14 INFO - 'SHLVL': '1', 05:46:14 INFO - 'TERM': 'linux', 05:46:14 INFO - 'TMOUT': '86400', 05:46:14 INFO - 'USER': 'cltbld', 05:46:14 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:14 INFO - '_': '/tools/buildbot/bin/python'} 05:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:14 INFO - Downloading/unpacking blobuploader==1.2.4 05:46:14 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 05:46:14 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 05:46:14 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 05:46:14 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 05:46:14 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 05:46:14 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 05:46:16 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:46:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46:17 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 05:46:17 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 05:46:17 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 05:46:17 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 05:46:17 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 05:46:17 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 05:46:17 INFO - Downloading docopt-0.6.1.tar.gz 05:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:17 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:17 INFO - Running setup.py install for blobuploader 05:46:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:46:17 INFO - Running setup.py install for requests 05:46:18 INFO - Running setup.py install for docopt 05:46:18 INFO - Successfully installed blobuploader requests docopt 05:46:18 INFO - Cleaning up... 05:46:18 INFO - Return code: 0 05:46:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:18 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 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:18 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 05:46:18 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 05:46:18 INFO - Using env: {'DISPLAY': ':0', 05:46:18 INFO - 'HOME': '/home/cltbld', 05:46:18 INFO - 'LANG': 'en_US.UTF-8', 05:46:18 INFO - 'LANGUAGE': 'en_US:en', 05:46:18 INFO - 'LOGNAME': 'cltbld', 05:46:18 INFO - 'MAIL': '/var/mail/cltbld', 05:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:18 INFO - 'MOZ_NO_REMOTE': '1', 05:46:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:18 INFO - 'NO_EM_RESTART': '1', 05:46:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:18 INFO - 'PWD': '/builds/slave/test', 05:46:18 INFO - 'SHELL': '/bin/bash', 05:46:18 INFO - 'SHLVL': '1', 05:46:18 INFO - 'TERM': 'linux', 05:46:18 INFO - 'TMOUT': '86400', 05:46:18 INFO - 'USER': 'cltbld', 05:46:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:18 INFO - '_': '/tools/buildbot/bin/python'} 05:46:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:18 INFO - Running setup.py (path:/tmp/pip-QR8cim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:18 INFO - Running setup.py (path:/tmp/pip-aYjCxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:18 INFO - Running setup.py (path:/tmp/pip-K7iz_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:18 INFO - Running setup.py (path:/tmp/pip-6j7Guc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:18 INFO - Running setup.py (path:/tmp/pip-Wzpy_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:18 INFO - Running setup.py (path:/tmp/pip-qL53Bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:18 INFO - Running setup.py (path:/tmp/pip-7zIRse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:18 INFO - Running setup.py (path:/tmp/pip-mn2bKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:18 INFO - Running setup.py (path:/tmp/pip-w8jpWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:19 INFO - Running setup.py (path:/tmp/pip-WKoWLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:19 INFO - Running setup.py (path:/tmp/pip-AitnFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:19 INFO - Running setup.py (path:/tmp/pip-zqnwry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:19 INFO - Running setup.py (path:/tmp/pip-38BUJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:19 INFO - Running setup.py (path:/tmp/pip-KT4Sj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:19 INFO - Running setup.py (path:/tmp/pip-JS6JWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:19 INFO - Running setup.py (path:/tmp/pip-EE12Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:19 INFO - Running setup.py (path:/tmp/pip-VwxSA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:19 INFO - Running setup.py install for manifestparser 05:46:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:46:19 INFO - Running setup.py install for mozcrash 05:46:19 INFO - Running setup.py install for mozdebug 05:46:19 INFO - Running setup.py install for mozdevice 05:46:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Running setup.py install for mozfile 05:46:20 INFO - Running setup.py install for mozhttpd 05:46:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Running setup.py install for mozinfo 05:46:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Running setup.py install for mozInstall 05:46:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Running setup.py install for mozleak 05:46:20 INFO - Running setup.py install for mozlog 05:46:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Running setup.py install for moznetwork 05:46:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Running setup.py install for mozprocess 05:46:20 INFO - Running setup.py install for mozprofile 05:46:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:46:20 INFO - Running setup.py install for mozrunner 05:46:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:46:21 INFO - Running setup.py install for mozscreenshot 05:46:21 INFO - Running setup.py install for moztest 05:46:21 INFO - Running setup.py install for mozversion 05:46:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:46:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:21 INFO - Cleaning up... 05:46:21 INFO - Return code: 0 05:46:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:21 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 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:21 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 05:46:21 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 05:46:21 INFO - Using env: {'DISPLAY': ':0', 05:46:21 INFO - 'HOME': '/home/cltbld', 05:46:21 INFO - 'LANG': 'en_US.UTF-8', 05:46:21 INFO - 'LANGUAGE': 'en_US:en', 05:46:21 INFO - 'LOGNAME': 'cltbld', 05:46:21 INFO - 'MAIL': '/var/mail/cltbld', 05:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:21 INFO - 'MOZ_NO_REMOTE': '1', 05:46:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:21 INFO - 'NO_EM_RESTART': '1', 05:46:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:21 INFO - 'PWD': '/builds/slave/test', 05:46:21 INFO - 'SHELL': '/bin/bash', 05:46:21 INFO - 'SHLVL': '1', 05:46:21 INFO - 'TERM': 'linux', 05:46:21 INFO - 'TMOUT': '86400', 05:46:21 INFO - 'USER': 'cltbld', 05:46:21 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:21 INFO - '_': '/tools/buildbot/bin/python'} 05:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:21 INFO - Running setup.py (path:/tmp/pip-j0aMLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:21 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)) 05:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:21 INFO - Running setup.py (path:/tmp/pip-8s3moD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:21 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)) 05:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:21 INFO - Running setup.py (path:/tmp/pip-QvvGQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:21 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)) 05:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:21 INFO - Running setup.py (path:/tmp/pip-WjfDHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:21 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)) 05:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:21 INFO - Running setup.py (path:/tmp/pip-Y7XWjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:21 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)) 05:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:21 INFO - Running setup.py (path:/tmp/pip-QFC9pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:22 INFO - Running setup.py (path:/tmp/pip-iRayQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:22 INFO - Running setup.py (path:/tmp/pip-OgyMpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:22 INFO - Running setup.py (path:/tmp/pip-l5SA85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:22 INFO - Running setup.py (path:/tmp/pip-VmTeq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:22 INFO - Running setup.py (path:/tmp/pip-QbhDaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:22 INFO - Running setup.py (path:/tmp/pip-8KDzWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:22 INFO - Running setup.py (path:/tmp/pip-XndD0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:22 INFO - Running setup.py (path:/tmp/pip-Mv2XS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:22 INFO - Running setup.py (path:/tmp/pip-gxQqNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:22 INFO - Running setup.py (path:/tmp/pip-D1TosC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:22 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)) 05:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:22 INFO - Running setup.py (path:/tmp/pip-cbtr7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:22 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)) 05:46:22 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)) 05:46:22 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)) 05:46:22 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)) 05:46:22 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)) 05:46:22 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)) 05:46:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:22 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 05:46:22 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 05:46:22 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 05:46:22 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 05:46:22 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 05:46:22 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 05:46:24 INFO - Downloading blessings-1.6.tar.gz 05:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:24 INFO - Installing collected packages: blessings 05:46:24 INFO - Running setup.py install for blessings 05:46:24 INFO - Successfully installed blessings 05:46:24 INFO - Cleaning up... 05:46:24 INFO - Return code: 0 05:46:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:46:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:25 INFO - Reading from file tmpfile_stdout 05:46:25 INFO - Current package versions: 05:46:25 INFO - argparse == 1.2.1 05:46:25 INFO - blessings == 1.6 05:46:25 INFO - blobuploader == 1.2.4 05:46:25 INFO - docopt == 0.6.1 05:46:25 INFO - manifestparser == 1.1 05:46:25 INFO - mozInstall == 1.12 05:46:25 INFO - mozcrash == 0.17 05:46:25 INFO - mozdebug == 0.1 05:46:25 INFO - mozdevice == 0.48 05:46:25 INFO - mozfile == 1.2 05:46:25 INFO - mozhttpd == 0.7 05:46:25 INFO - mozinfo == 0.9 05:46:25 INFO - mozleak == 0.1 05:46:25 INFO - mozlog == 3.2 05:46:25 INFO - moznetwork == 0.27 05:46:25 INFO - mozprocess == 0.22 05:46:25 INFO - mozprofile == 0.28 05:46:25 INFO - mozrunner == 6.11 05:46:25 INFO - mozscreenshot == 0.1 05:46:25 INFO - mozsystemmonitor == 0.3 05:46:25 INFO - moztest == 0.7 05:46:25 INFO - mozversion == 1.4 05:46:25 INFO - psutil == 3.1.1 05:46:25 INFO - requests == 1.2.3 05:46:25 INFO - wsgiref == 0.1.2 05:46:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:25 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')]} 05:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:25 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')]} 05:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:25 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 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:25 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 05:46:25 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 05:46:25 INFO - Using env: {'DISPLAY': ':0', 05:46:25 INFO - 'HOME': '/home/cltbld', 05:46:25 INFO - 'LANG': 'en_US.UTF-8', 05:46:25 INFO - 'LANGUAGE': 'en_US:en', 05:46:25 INFO - 'LOGNAME': 'cltbld', 05:46:25 INFO - 'MAIL': '/var/mail/cltbld', 05:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:25 INFO - 'MOZ_NO_REMOTE': '1', 05:46:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:25 INFO - 'NO_EM_RESTART': '1', 05:46:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:25 INFO - 'PWD': '/builds/slave/test', 05:46:25 INFO - 'SHELL': '/bin/bash', 05:46:25 INFO - 'SHLVL': '1', 05:46:25 INFO - 'TERM': 'linux', 05:46:25 INFO - 'TMOUT': '86400', 05:46:25 INFO - 'USER': 'cltbld', 05:46:25 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:25 INFO - '_': '/tools/buildbot/bin/python'} 05:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 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)) 05:46:25 INFO - Cleaning up... 05:46:25 INFO - Return code: 0 05:46:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:46:25 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')]} 05:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:25 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')]} 05:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:25 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 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:25 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 05:46:25 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 05:46:25 INFO - Using env: {'DISPLAY': ':0', 05:46:25 INFO - 'HOME': '/home/cltbld', 05:46:25 INFO - 'LANG': 'en_US.UTF-8', 05:46:25 INFO - 'LANGUAGE': 'en_US:en', 05:46:25 INFO - 'LOGNAME': 'cltbld', 05:46:25 INFO - 'MAIL': '/var/mail/cltbld', 05:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:25 INFO - 'MOZ_NO_REMOTE': '1', 05:46:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:25 INFO - 'NO_EM_RESTART': '1', 05:46:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:25 INFO - 'PWD': '/builds/slave/test', 05:46:25 INFO - 'SHELL': '/bin/bash', 05:46:25 INFO - 'SHLVL': '1', 05:46:25 INFO - 'TERM': 'linux', 05:46:25 INFO - 'TMOUT': '86400', 05:46:25 INFO - 'USER': 'cltbld', 05:46:25 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:25 INFO - '_': '/tools/buildbot/bin/python'} 05:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:25 INFO - Downloading/unpacking jsonschema 05:46:25 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 05:46:25 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 05:46:25 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 05:46:25 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 05:46:25 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 05:46:25 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 05:46:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:27 INFO - Downloading/unpacking functools32 (from jsonschema) 05:46:27 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 05:46:27 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 05:46:27 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 05:46:27 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 05:46:27 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 05:46:27 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 05:46:27 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:27 INFO - warning: no files found matching '*.txt' 05:46:27 INFO - no previously-included directories found matching 'build' 05:46:27 INFO - no previously-included directories found matching 'dist' 05:46:27 INFO - no previously-included directories found matching '.git*' 05:46:27 INFO - Installing collected packages: jsonschema, functools32 05:46:27 INFO - Running setup.py install for functools32 05:46:27 INFO - warning: no files found matching '*.txt' 05:46:27 INFO - no previously-included directories found matching 'build' 05:46:27 INFO - no previously-included directories found matching 'dist' 05:46:27 INFO - no previously-included directories found matching '.git*' 05:46:27 INFO - Successfully installed jsonschema functools32 05:46:27 INFO - Cleaning up... 05:46:27 INFO - Return code: 0 05:46:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:27 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 0x7f4befba1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2401770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, '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': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', '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 05:46:27 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 05:46:27 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 05:46:28 INFO - Using env: {'DISPLAY': ':0', 05:46:28 INFO - 'HOME': '/home/cltbld', 05:46:28 INFO - 'LANG': 'en_US.UTF-8', 05:46:28 INFO - 'LANGUAGE': 'en_US:en', 05:46:28 INFO - 'LOGNAME': 'cltbld', 05:46:28 INFO - 'MAIL': '/var/mail/cltbld', 05:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:28 INFO - 'MOZ_NO_REMOTE': '1', 05:46:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:28 INFO - 'NO_EM_RESTART': '1', 05:46:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:28 INFO - 'PWD': '/builds/slave/test', 05:46:28 INFO - 'SHELL': '/bin/bash', 05:46:28 INFO - 'SHLVL': '1', 05:46:28 INFO - 'TERM': 'linux', 05:46:28 INFO - 'TMOUT': '86400', 05:46:28 INFO - 'USER': 'cltbld', 05:46:28 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:28 INFO - '_': '/tools/buildbot/bin/python'} 05:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:28 INFO - Downloading/unpacking flake8 05:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:46:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:46:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:46:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:46:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:46:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:46:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:46:30 INFO - no previously-included directories found matching 'docs/_build' 05:46:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:46:30 INFO - Running setup.py install for pep8 05:46:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:46:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:46:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:46:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:46:30 INFO - no previously-included directories found matching 'docs/_build' 05:46:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:46:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:46:31 INFO - Cleaning up... 05:46:31 INFO - Return code: 0 05:46:31 INFO - Running post-action listener: _resource_record_post_action 05:46:31 INFO - Running post-action listener: _start_resource_monitoring 05:46:31 INFO - Starting resource monitoring. 05:46:31 INFO - ##### 05:46:31 INFO - ##### Finished create-virtualenv step (success) 05:46:31 INFO - ##### 05:46:31 INFO - ##### 05:46:31 INFO - ##### Running install step. 05:46:31 INFO - ##### 05:46:31 INFO - Running pre-action listener: _resource_record_pre_action 05:46:31 INFO - Running main action method: install 05:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:31 INFO - Reading from file tmpfile_stdout 05:46:31 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:46:31 INFO - Reading from file tmpfile_stdout 05:46:31 INFO - Output received: 05:46:31 INFO - Usage: mozinstall [options] installer 05:46:31 INFO - Options: 05:46:31 INFO - -h, --help show this help message and exit 05:46:31 INFO - -d DEST, --destination=DEST 05:46:31 INFO - Directory to install application into. [default: 05:46:31 INFO - "/builds/slave/test"] 05:46:31 INFO - --app=APP Application being installed. [default: firefox] 05:46:31 INFO - mkdir: /builds/slave/test/build/application 05:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:46:48 INFO - Reading from file tmpfile_stdout 05:46:48 INFO - Output received: 05:46:48 INFO - /builds/slave/test/build/application/firefox/firefox 05:46:48 INFO - Running post-action listener: _resource_record_post_action 05:46:48 INFO - ##### 05:46:48 INFO - ##### Finished install step (success) 05:46:48 INFO - ##### 05:46:48 INFO - ##### 05:46:48 INFO - ##### Running run-tests step. 05:46:48 INFO - ##### 05:46:48 INFO - Running pre-action listener: _resource_record_pre_action 05:46:48 INFO - Running main action method: run_tests 05:46:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:46:48 INFO - Python 2.7.3 05:46:48 INFO - Return code: 0 05:46:48 INFO - grabbing minidump binary from tooltool 05:46:48 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')]} 05:46:48 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24909c0>, '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 0x24918c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2491d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:46:48 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 05:46:48 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 05:46:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:46:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:48 INFO - Return code: 0 05:46:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:46:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:46:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:46:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:46:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:46:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:46:49 INFO - Return code: 0 05:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 05:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 05:46:49 INFO - Using env: {'DISPLAY': ':0', 05:46:49 INFO - 'HOME': '/home/cltbld', 05:46:49 INFO - 'LANG': 'en_US.UTF-8', 05:46:49 INFO - 'LANGUAGE': 'en_US:en', 05:46:49 INFO - 'LOGNAME': 'cltbld', 05:46:49 INFO - 'MAIL': '/var/mail/cltbld', 05:46:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:46:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:49 INFO - 'MOZ_NO_REMOTE': '1', 05:46:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:49 INFO - 'NO_EM_RESTART': '1', 05:46:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:49 INFO - 'PWD': '/builds/slave/test', 05:46:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:46:49 INFO - 'SHELL': '/bin/bash', 05:46:49 INFO - 'SHLVL': '1', 05:46:49 INFO - 'TERM': 'linux', 05:46:49 INFO - 'TMOUT': '86400', 05:46:49 INFO - 'USER': 'cltbld', 05:46:49 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059', 05:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:49 INFO - '_': '/tools/buildbot/bin/python'} 05:46:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 05:46:49 INFO - mozversion application_buildid: 20160602030220 05:46:49 INFO - mozversion application_changeset: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 05:46:49 INFO - mozversion application_display_name: Nightly 05:46:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:49 INFO - mozversion application_name: Firefox 05:46:49 INFO - mozversion application_remotingname: firefox 05:46:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:46:49 INFO - mozversion application_vendor: Mozilla 05:46:49 INFO - mozversion application_version: 49.0a1 05:46:49 INFO - mozversion platform_buildid: 20160602030220 05:46:49 INFO - mozversion platform_changeset: 34a8be4346a9231e472fc36b1d7c0531e0fbf7c5 05:46:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:46:49 INFO - mozversion platform_version: 49.0a1 05:46:49 INFO - using testdate: 1464871609 05:46:49 INFO - actual date: 1464871609 05:46:49 INFO - starting webserver on 'localhost:40353' 05:46:49 INFO - SUITE-START | Running 2 tests 05:46:49 INFO - TEST-START | dromaeo_css 05:46:49 INFO - operating with platform_type : linux_ 05:46:49 INFO - Initialising browser for dromaeo_css test... 05:46:49 INFO - TEST-INFO | started process 13315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOcEiCW/profile http://localhost:40353/getInfo.html) 05:46:53 INFO - PROCESS | 13315 | __metrics Screen width/height:1600/1200 05:46:53 INFO - PROCESS | 13315 | colorDepth:24 05:46:53 INFO - PROCESS | 13315 | Browser inner width/height: 1024/697 05:46:53 INFO - PROCESS | 13315 | __metrics 05:46:54 ERROR - PROCESS | 13315 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:46:54 INFO - TEST-INFO | 13315: exit 0 05:46:54 INFO - Browser initialized. 05:46:54 INFO - Running cycle 1/1 for dromaeo_css test... 05:46:54 INFO - TEST-INFO | started process 13399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOcEiCW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:06 INFO - PROCESS | 13399 | RSS: Main: 155705344 05:47:06 INFO - PROCESS | 13399 | 05:47:57 INFO - PROCESS | 13399 | 1464871677654 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] 05:50:05 INFO - PROCESS | 13399 | Cycle 1(1): loaded http://localhost:40353/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40353/tests/dromaeo/cssquery-ext.html) 05:50:06 INFO - PROCESS | 13399 | RSS: Main: 254963712 05:50:06 INFO - PROCESS | 13399 | 05:53:08 INFO - PROCESS | 13399 | Cycle 1(1): loaded http://localhost:40353/tests/dromaeo/cssquery-ext.html (next: http://localhost:40353/tests/dromaeo/cssquery-jquery.html) 05:53:09 INFO - PROCESS | 13399 | RSS: Main: 240181248 05:53:09 INFO - PROCESS | 13399 | 05:56:11 INFO - PROCESS | 13399 | Cycle 1(1): loaded http://localhost:40353/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40353/tests/dromaeo/cssquery-mootools.html) 05:56:11 INFO - PROCESS | 13399 | RSS: Main: 245903360 05:56:11 INFO - PROCESS | 13399 | 05:59:13 INFO - PROCESS | 13399 | Cycle 1(1): loaded http://localhost:40353/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40353/tests/dromaeo/cssquery-prototype.html) 05:59:14 INFO - PROCESS | 13399 | RSS: Main: 235347968 05:59:14 INFO - PROCESS | 13399 | 06:02:16 INFO - PROCESS | 13399 | Cycle 1(1): loaded http://localhost:40353/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40353/tests/dromaeo/cssquery-yui.html) 06:02:17 INFO - PROCESS | 13399 | RSS: Main: 233725952 06:02:17 INFO - PROCESS | 13399 | 06:05:19 INFO - PROCESS | 13399 | Cycle 1(1): loaded http://localhost:40353/tests/dromaeo/cssquery-yui.html (next: http://localhost:40353/tests/dromaeo/cssquery-dojo.html) 06:05:20 INFO - PROCESS | 13399 | RSS: Main: 284319744 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | __start_tp_report 06:05:20 INFO - PROCESS | 13399 | _x_x_mozilla_page_load 06:05:20 INFO - PROCESS | 13399 | _x_x_mozilla_page_load_details 06:05:20 INFO - PROCESS | 13399 | |i|pagename|runs| 06:05:20 INFO - PROCESS | 13399 | |0;dojo.html;3136.6079240094987;3148.9842550787243;3163.6361818390883;3164.620245570532;3204.0868352519524;5383.380911195214;5400.864978375543;5448.45515448455;5477.315335583052;5514.696691681956;5429.185622156679;5461.563074954004;5479.726013699319;5484.533814625762;5516.779328826842;4885.267209918514;4886.071646387175;5023.824166154185;5069.2649565812935;5129.9230511542355;5036.093503169428;5060.569851562622;5084.3644544431945;5089.872753181163;5095.923561146586;4845.200541910581;4871.82948596801;4882.682625629358;4954.702717836905;4993.6254780891395;4966.528179822911;4976.975115124401;4978.6514943953935;4985.501449855022;5034.84895453137;4913.606911447076;4944.9368385797225;4952.87617809554;4958.033183529214;4960.355153830015;5247.160454327328;5257.579393648499;5340.706261155635;5361.08861493547;5456.863578410532;5807.390224026501;5831.9416805831825;5840.211571437844;5911.852203694899;5928.318243402021;5987.760489580412;5990.9101363479585;5998.130271110665;6007.789727359522;6008.098785182214;5311.203319502067;5458.153986132156;5482.479164479368;5506.476884695948;5565.248691426606;6052.700752371906;6059.791268323752;6065.975194600972;6068.601583113421;6074.179985701962;5578.665280083208;5774.740136693859;5817.592768506249;5830.3586605473365;5830.7084645767545;5816.738246778905;5878.647281163144;5883.558733095035;5898.557608179403;5905.291365036223;5818.738156782955;5855.2973643163095;5867.853303667443;5873.383294754075;5881.029630111069;5634.5210657093785;5728.885422291614;5847.44449277318;5879.353271140171;5901.793437229677;4393.209222339946;4405.691601587858;4407.162639098561;4452.220861349251;4457.175422546839;3901.8439262429183;3913;3925.3523168676847;3927.8232479538487;3955.4462375267485;5308.150695888639;5361.490658387447;5426.403095659474;5471.37079235893;5505.449455054462;5871.442212989845;5911.852203694942;5946.940530594638;5953.493953014043;6000.609960352563;5504.4495550444635;5510.8071217507195;5524.723763811793;5526.309211348582;5533.944660553343;4895.755212239374;5000.000000000015;5053.393592768891;5060.493950604984;5062.468440813662;6061.7575296987625;6071.058985857199;6073.544484163705;6081.209442772411;6081.57428979976;5929.436703513159;5935.970320148372;5937.703114844327;5940.762369505172;5942.435468630473;5886;5938.465538101592;5951.7619295227705;5966.8209953699725;5983.431574000463;5731.856703582277;5735.139729040678;5735.512481439125;5735.885282294247;5737.655740655574;5714.885702286014;5724.370319264961;5863.824085277449;5868.4424979626865;5874.94125058744;5673.092305231308;5685.573581981451;5714.514266287412;5752.28096487939;5763.221965034667;5714.5999780015;5727.8568035799435;5729.427057294237;5732.742026608896;5735.426457354231;5427.48438898304;5547.611667183259;5615.915761263503;5621.8594535136945;5636.689982051026;5694.629849059798;5825.213596164633;5826.592138550431;5839.970800146142;5841.678707671119;5607.971960140173;5634.830955071355;5656.575756818337;5657.236273103078;5697.914531281951 06:05:20 INFO - PROCESS | 13399 | |1;ext.html;596.8746563221723;613.1905884232813;626.7273735924875;638.84348334658;646.0728854094372;33499.33001339975;43762;44031.33952990704;44221.778891105496;47613;44093.33859992103;44631.77684111583;44722.77638611811;44737;44756.10487790242;7995.760127196193;8727.865377500932;8929.687460938869;8934.821303573925;10369.637062702806;10158.492075396238;10573.841392379121;10614.57541698331;10651.30766500175;10653.041226289632;10666.733331666694;10724.78550428991;10739;10740.838887416696;10741.677749667484;8030.07654119772;10330.535125919352;10567.682969510888;10598.152147828156;10599.894001059967;10543.156547476183;10611.204159687977;10634.468276586216;10637.414942178177;10642.361458312525;41008.794956025326;41308.586914131076;41372.172556549005;41401.585984140074;41488.79255603703;3497.562804649419;3552.3960926642535;3561.3233485637907;3738.028112690693;4121.752694838289;31793.6820631793;32137.357252855047;32344;32355.352892942246;32379.838100809582;4260.68044896634;4287.464066991626;4411.6250118739945;4447.3996010538485;4454.153710794939;242453;243588;243649;243663;244079;50038.74980625074;54801.45198548043;55614.721926390106;55666.44333556694;60317;59818;60011.69994150001;60128;60146.39853601495;60253.69873150606;15936.840631593768;15980.600484987968;16016.599585010468;16022.519324420286;16049.839501604834;13108.344582770824;13184.274864882524;13214.339283036003;13524.256165910772;13532.864671353358;16212.189390530426;16386.75419868703;16405.097719625534;16410.425635102714;16411.42560010394;5585.357683866326;6759.628220447824;6760.977218554952;6903.1716194057035;6912.343327383891;1018.8267994440963;1044.9373037617768;1058.5501162006117;1108.2685427617735;1119.1103073056777;31378.37243255122;31992.200194995312;31994.200144996095;32247.193820154214;32641;1153.2561497834017;1185.7035741064733;1253.1040306181123;1253.2355263289387;1278.111712359901;16895.577610559834;16952.74570881438;16965.91517042407;17204.483865484057;17217.483475495756;467085;470659;470925;473250;474269;56299.437005630236;56417;56424.43575564192;56444.871102577716;56464;1290.5805613175755;1377.3113443278362;1410.7178564286978;1423.0394483723653;1428.4286285485682;31240.843795780875;32352.83823580867;33268.33463330672;33290.334193316485;33468;48297.27554086762;48652;48966.79649415856;49049;49118.26322605092;371084;371759;373026;373221;373588;34786.478202827486;39105.217895641355;39749.403758944216;39924.401133982436;43144.78427607968;47517.76241118772;47710.76144619255;47788.2831757517;48310.75844620755;48596.514034860615;40215.79892100521;40919.795401022806;41131.1773764529;41933.79033104815;42154.7892260549;40606.79696601498;40632;40646.39030414487;41390.37914431225;41452.58547414608;40313.59686403216;40939;41026.58973410228;41269.58730412657;41370.379444308965;35200.47199292064;35419.29161416809;35905.46141807823;36135;36149.638503614624;27994.44011119807;28022;28222.576661349685;28281.292967675974;28334.71665283321 06:05:20 INFO - PROCESS | 13399 | |2;jquery.html;379.4952712891853;381.78429187509056;391.39334032250014;396.2451529835664;401.0335092427251;17369.131543422827;18830.529236769053;19161.329353472633;19178.90410547947;19180;17610.11949402528;18090.4572862814;18549.35077272296;19300.806991930112;19341;5196.350456192976;5292.947070529303;5324.840254792362;5372.597055220855;5378.327709036371;5172.741362931857;5250.212468129792;5252.894942101156;5289.920651190235;5298.178782288752;5290.920636190441;5307.575393968474;5307.575393968493;5309.601779866506;5579.38626751057;5552.944470555303;5717.313922329306;5738.167965644979;5739.7991070312755;5741.942580574183;5019.723915184663;5152.896942061176;5157.613179011551;5157.664751791159;5343;13472.989525785508;13609.591712248648;13687.931560342235;13721.725565488734;19845.206191752313;2143.8928053597415;2249.5163539839014;2250.1561914282142;2580.6516120323704;2613.6732908386452;9705.708828735149;10139.54372053252;10342.896571034269;12626.368681565977;13292.220967306743;1646.8517833395051;1740.8694347923956;1879.1637721213929;1941.4466876940146;1987.9602407951904;51030.744846275906;82727.17272827255;82976.58511707463;83190;83720.58139709262;10843.349399036084;11363.261388009918;11411.087113030937;11549.942250288863;11970.82043769344;15166.31751571182;15284.082955022512;15362.539123826304;15382.077075375513;15397.230138493254;7868.527888326605;8216.351352691949;8337.458065225741;8356.456830306011;8652.307815374756;7691.653875575613;7716.575588342583;7734.342580880688;7868.291853733077;8007.439479236514;4681.063787242565;4788.353572267699;4808.567228949411;4921.309637771363;4924.803007879645;1964.0768838645813;1982.7521559805025;1984.781674015857;1994.4415563642203;2024.0588126521238;898.9730308090768;950.1543626172712;952.1478276942098;1004.638330201127;1024.2164743970868;17938.372156974445;18941.43175704731;19218.4234472966;19303.613927721366;19439.416817495498;940.4968341937031;980.612658000086;1001.9760084629008;1024.283001898674;1057.7144171073921;10333.741656458498;10666.893331066745;10677.519511621995;10732.731681707864;10747.946260268649;490381.5969688164;491960;497246;502612.3040771957;529280;53296.73351633217;92503.53748231215;93199.53400233091;94871.52564237273;95211;995.6515219673201;1023.9385636861663;1034.7516596017051;1042.0777611813598;1052.736815796051;17537;17775.644487109927;17852.285908563514;18024.549386264924;18051.63896722032;41338.379924301764;41658.79170604127;42385.152296954504;42803.57196427996;43553.12893742044;58246.12630810626;59077.70461147667;59232.40767592269;59576.70211648914;60846;7285.8178545534665;7778;8147.470414423044;8699.391042626956;8984.371094023356;9694.806103878023;13274.027037280879;13535.79696304576;13648.863511365156;13712.794308085187;11280.943595281971;11700.941495292469;11783.587574434854;11965.521379144737;11988;8100.825380319939;11162.944185279022;11601.709957251136;11669.649910502698;11798.705032373917;11897.881021189678;12104.576339828063;12154.392280386122;12166.878331216929;12184.512619495476;8080.878786818085;8100.87848682282;8121.309688676529;8204.794880128045;8277.875831862648;11279;11499.712507187387;11639.941800290944;11768.882311176778;12058.15592908488 06:05:20 INFO - PROCESS | 13399 | |3;mootools.html;1341.1349679456744;1509.713154500645;1536.508317338452;1721.681488924549;1858.6933156029256;8135.755927322182;8483.490990540564;8515.574221288942;8706.825863482727;8726.738197854074;8034.357251419887;8265.214804593554;8463.280621147194;8474.661013559451;8475.95762021189;2007.046652839904;2293.8623682579014;2361.386039629696;2367.5264947010583;2411.1801987324307;2409.494006258679;2411.686480757499;2413.613821788514;2419.1532963462823;2420.1529464687396;2297.7587353327826;2354.470244195051;2358.280724379072;2359.7876191142796;2362.244081893795;1991.9701204481944;2309.1109922679807;2349.835511514195;2379.298107058422;2387.7492863249454;2307.8038366738847;2321.419645088728;2328.0571368595747;2328.6507023946374;2364.5625559271416;8484.109168537338;8574.099719529484;8599.656013759442;8610.78473038179;8612;5303.257543943851;5424.05079111154;5426.8371948841595;5438.211459338413;5442.673439593597;3400.387930172568;3482.6343233960574;3528.1179705073732;3538.610752817188;3580.5166302549083;1099.5217080569898;1110.9222354435192;1118.815395459748;1140.2645293785463;1161.134954458925;11907.464164112549;12154.756904861853;12177.147599667938;12196.756064878742;12202.084843636763;4658.650601204856;4704.341392205094;4783.832565860178;4836.613070954315;4890.559849613481;10443.791124177475;10465.267431279737;10475.109615682752;10483.423411712429;10487.842682359771;4634.976825115853;5370.677759334452;5390.407055223922;5397.865053373618;5399.37907140676;4902.313676085351;5027.4469808321055;5041.269015992661;5066.645334826527;5071.7971281149075;3970.024087786609;4026.838926442968;4056.5334986476932;4177.394277829759;4183.958160418357;1277.3294020639053;1327.9615789762668;1328.548293580168;1343.543195313598;1347.9123856949268;1013.8732658417698;1015.8577799108131;1020.3673722292226;1024.533837104116;1030.1655658916302;6081.908771368433;6135.202423684982;6210.441060304594;6225.3774622538285;6278;465.16038550416823;466.3634139399695;468.40278644728244;473.3704173449304;482.1441940555472;4254.680898932593;4280.293751530962;4282.400463935051;4283.635890949242;4290.678199135077;10458.790824183627;10964.122870170368;10977.725556861142;11004.889951100547;11014.118870490342;10596.947015264874;10842.186835987333;10867.130629549618;10872.619458319079;10995.120390368751;7896.842063158705;7941.722039728582;7996.480228785111;8035.638396272182;8044.2760151586635;5828.930729590934;5851.21008663842;5933.584649074523;6065.211522502223;6114.969425152845;9951.850722239027;9971.102600765676;10129.088382045651;10188.388696678223;10197.9490102549;10031.849522257025;10306.33008854422;10316.226283028953;10370.896291036994;10408.167346612405;6843;7324.707011719472;7655.50239234448;7790.220977902164;7862.253085956825;8332.458390204618;8377.078521362768;8413.3269338454;8458.788530286545;8465;7317.597532135784;7487.2138425464545;7502.774916752506;7599.734009309647;7604.923950760422;7426.443016773872;7477.439192060726;7509.88735168962;7533.924660753322;7576.6969321228735;7035.366816986496;7058.788236352918;7091.503594748525;7096.1839388468925;7097;5026.52248036436;5097.362829646294;5143.459936706592;5229.424763276113;5259.815906443409;5190.506901844319;5315.681059136464;5342.8931421372135;5382.381026181883;5409.242706021081 06:05:20 INFO - PROCESS | 13399 | |4;prototype.html;332.70908873570545;337.1302040734904;342.218031797343;342.57520674363786;346.3920818962723;5415.296011518507;5426.8371948841495;5432.70120143392;5484.506394424501;5536.9446305536985;5311.282976798131;5355.384130824957;5373.382061062979;5379.246905433242;5457.772001299705;3768.8304026318883;4026.4362989181463;4060.8375664973514;4131.421600975866;4142.212979533894;4156.397322388267;4209.6421804146685;4221.26127927613;4226.577342265764;4240.342746874239;4142.958570414303;4167.666586673074;4173.686973476986;4182.33082706767;4200.684948628865;3976.125252444472;4123.257813593552;4141.343462614963;4170.207660544487;4179.477565304337;3691.9261614767556;3698;3712.554493460775;3716.0338312038793;3732.5520937487677;5177.818776342848;5179.627398739337;5193.844184674465;5195.532402083831;5215.687058776485;4715.1512727709;4743.294449810556;4773.928391074137;4790.257510085942;4793.904121917542;5599.636023658449;5606.383297837235;5616.241807355996;5618.2415373924405;5631.380548139701;4076.6331030207425;4099.560761346994;4100.036491424513;4106;4107.199096176247;5721.799737009226;5747.82756517301;5808.97095514524;5832.300123985064;5871.765129394819;4117.649999749995;4172.603602657743;4186.748795072305;4190.434291370687;4190.748555086643;4238.19474299882;4254.957450425456;4256.7445953242905;4271;4275.273203555403;4698.342232087511;4782.521747825259;4829.275608658659;4848.733319667452;4877.268409738567;5682.943170568324;5686.2039314496005;5693.231413759172;5698.088305871041;5699.287589051369;1463.239115659851;1463.3488097796592;1477.3351991603822;1491.1351416178807;1493.6041948883556;916.8166366726548;951.8572214167792;958.6309270930742;967.3905439573024;968.3221744778683;4712.764361781966;4798.760061996886;4812.470628230892;4853.247746599212;4853.708777473363;5135.717535535581;5149.957283532409;5152.639315247971;5167.534921856975;5174.8965020700125;4556.7721613919175;4797.376341075638;4821.216150024443;4848.5636292733825;4853.015395531793;2745.272502786763;2776.3059235191204;2778.5415406458114;2781.9721802781714;2818.647049416292;5272.260154149121;5286.233496143038;5293.364796224478;5308.893822123536;5317.73411329432;4925.458199598042;5263.868268322329;5280;5353.544948679328;5354.705491197944;5346.732663366816;5349.251104845324;5353.330833645794;5357.8392648220615;5394.406615272317;5133.460986596503;5169.793208271627;5226.555742761908;5230.495567969546;5279.366476022902;4854.927176092291;5119.897602048012;5126.923096153635;5159.355080614923;5191.662541934762;5215.243789650555;5273.70794155419;5290.418054014132;5300.70846103468;5307.310049693515;3826.789526576065;3845.192509573021;3846.288401661234;3861.8455261789213;3870.477485539416;5017.8243761468175;5110.821121260738;5120.771014956458;5133.6919784813035;5188.429272780067;5026.346574945233;5039.722815245197;5041.2438134280155;5053.191489361684;5087.313212716236;4945.777440015136;4966.478519755374;4973.825916092919;5010.797408621832;5043.604287963188;4596.88507787308;4903.156169421798;4907.68100073494;4931.013797240638;4974.8756281093265;5025.246213068069;5041.873953151201;5064.113780088396;5068.391792984866;5107.284980102714;4813.518648135158;4942.084170339517;4965.975170124127;4979.1618777298045;5003.374578177728 06:05:20 INFO - PROCESS | 13399 | |5;yui.html;674.4873895839161;716.4554938246933;732.9340359367656;798.7603718884335;846.5978660136434;14569.271536423175;15780.921095394522;16079.83920160798;16085.91957040216;16095.436659716912;14847.1091734496;15003.774943375858;15013.849861501381;15022.924885375585;15291.923540382282;816.0615292413742;818.071488860145;823.0288259853371;823.3989187892842;824.1305422778966;812.8821320908464;813.6582635293191;814.2183503836295;814.9103598604149;815.7267315449332;863.5595846118462;867.4058270085012;869.5043825019741;869.5826003518315;869.7043005378171;857.8670306102501;860.7546849147994;861.7845538615346;861.9094995025526;862.021605477781;619.9473044791198;624.5628060357723;626.6115008694638;627.8273474794468;627.993720062798;14577.562673119823;14696.26518674062;14696.853031469655;14814.629634259121;14831.406743730238;12467.93766031164;13285.13646612967;13584.252866092362;13595.388207530685;13673.589792306248;12659.987201023896;12825.486980520767;13110.672233194151;13120.803187952188;13248.47006119754;4527.411436513232;4539.4779600346055;4550.567696068868;4550.7724613769515;4555.134524440346;62246.68876655633;63860.3613963859;64204.3579564203;72355;72387.63806180935;11568.826467602992;11655.76688466243;11681.591144310078;11831.349275789742;12086.87913120875;21401.250956216456;21417.143314267567;21421.250256240957;21428.03573839181;21455.570888582453;8356;8370.790730231793;8842.46945183291;8931.598078086501;9145.0397708241;4588.2888152336445;4626.953730462652;4818.831340903122;4932.8520144395725;5002.974390250063;3615.6926661233565;3679.632036796299;3718.8326525306425;3719;3722.8696995605023;586.0944840221867;588.7233000489763;595.1816252652603;598.7814447726548;613.8802933428028;604.353341924137;606.8816580766708;636.38270877249;675.6351570152161;696.1680791454265;8900.465972041698;9110.362274640844;9131.497767622845;9153.267738580898;9498.382605130644;510.4895104895105;633.0599060395309;640.5580149696694;680.1633990192091;686.543448606681;4247.8088486018205;4470.351798989193;4504.137972095954;4551.954480455153;4683.344331793552;66728;67333.66333168303;67438.66280668565;67523.66238168778;75533.24466755372;79311;89818.55090724505;94024.52987735017;94501;96854.51572742232;84317;84646.57676711577;85232;85914;86002.56998714966;8962.955185224033;9116.179543841086;9220.631174752934;9376.67181648639;9467.621295148117;51262.48737512577;51426.228606570185;51583.226251607004;52657.210141847136;52734.20898686446;67745.6612716953;68108.59457027228;73765;74399.25600743924;76076.61961690156;12012.159148859497;12620.873791261969;12798.744025119258;13028.54400095992;13583.592492225249;14324.57026289213;14717.852821471648;15364.46224382186;15520.14639194855;15698.529044128694;12878.098533102593;13229.140105893086;13261.138026028277;13265.469381224642;13303.26832024248;13773.655658608615;13902.16587004783;14018.789718154032;14223.857761422254;14905.25473726331;12153.513859445524;12539.561115360919;12632.6841828955;12775;12805.231686098863;10620.734481638021;10801.351918884891;10959.452027398758;10968.122550196125;11239.494222760159;10633.680989570325;10867.446150154548;11176.329420234812;11216.495257713586;11217.71955701114 06:05:20 INFO - PROCESS | 13399 | __end_tp_report 06:05:20 INFO - PROCESS | 13399 | __start_cc_report 06:05:20 INFO - PROCESS | 13399 | _x_x_mozilla_cycle_collect,788 06:05:20 INFO - PROCESS | 13399 | __end_cc_report 06:05:20 INFO - PROCESS | 13399 | __startTimestamp1464872720214__endTimestamp 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | ------- Summary: start ------- 06:05:20 INFO - PROCESS | 13399 | Number of tests: 6 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | [#0] dojo.html Cycles:165 Average:5431.23 Median:5634.83 stddev:631.16 (11.2%) stddev-sans-first:606.88 06:05:20 INFO - PROCESS | 13399 | Values: 3136.6 3149.0 3163.6 3164.6 3204.1 5383.4 5400.9 5448.5 5477.3 5514.7 5429.2 5461.6 5479.7 5484.5 5516.8 4885.3 4886.1 5023.8 5069.3 5129.9 5036.1 5060.6 5084.4 5089.9 5095.9 4845.2 4871.8 4882.7 4954.7 4993.6 4966.5 4977.0 4978.7 4985.5 5034.8 4913.6 4944.9 4952.9 4958.0 4960.4 5247.2 5257.6 5340.7 5361.1 5456.9 5807.4 5831.9 5840.2 5911.9 5928.3 5987.8 5990.9 5998.1 6007.8 6008.1 5311.2 5458.2 5482.5 5506.5 5565.2 6052.7 6059.8 6066.0 6068.6 6074.2 5578.7 5774.7 5817.6 5830.4 5830.7 5816.7 5878.6 5883.6 5898.6 5905.3 5818.7 5855.3 5867.9 5873.4 5881.0 5634.5 5728.9 5847.4 5879.4 5901.8 4393.2 4405.7 4407.2 4452.2 4457.2 3901.8 3913.0 3925.4 3927.8 3955.4 5308.2 5361.5 5426.4 5471.4 5505.4 5871.4 5911.9 5946.9 5953.5 6000.6 5504.4 5510.8 5524.7 5526.3 5533.9 4895.8 5000.0 5053.4 5060.5 5062.5 6061.8 6071.1 6073.5 6081.2 6081.6 5929.4 5936.0 5937.7 5940.8 5942.4 5886.0 5938.5 5951.8 5966.8 5983.4 5731.9 5735.1 5735.5 5735.9 5737.7 5714.9 5724.4 5863.8 5868.4 5874.9 5673.1 5685.6 5714.5 5752.3 5763.2 5714.6 5727.9 5729.4 5732.7 5735.4 5427.5 5547.6 5615.9 5621.9 5636.7 5694.6 5825.2 5826.6 5840.0 5841.7 5608.0 5634.8 5656.6 5657.2 5697.9 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | [#1] ext.html Cycles:180 Average:53899.39 Median:371421.50 stddev:98742.51 (26.6%) stddev-sans-first:98938.41 06:05:20 INFO - PROCESS | 13399 | Values: 596.9 613.2 626.7 638.8 646.1 33499.3 43762.0 44031.3 44221.8 47613.0 44093.3 44631.8 44722.8 44737.0 44756.1 7995.8 8727.9 8929.7 8934.8 10369.6 10158.5 10573.8 10614.6 10651.3 10653.0 10666.7 10724.8 10739.0 10740.8 10741.7 8030.1 10330.5 10567.7 10598.2 10599.9 10543.2 10611.2 10634.5 10637.4 10642.4 41008.8 41308.6 41372.2 41401.6 41488.8 3497.6 3552.4 3561.3 3738.0 4121.8 31793.7 32137.4 32344.0 32355.4 32379.8 4260.7 4287.5 4411.6 4447.4 4454.2 242453.0 243588.0 243649.0 243663.0 244079.0 50038.7 54801.5 55614.7 55666.4 60317.0 59818.0 60011.7 60128.0 60146.4 60253.7 15936.8 15980.6 16016.6 16022.5 16049.8 13108.3 13184.3 13214.3 13524.3 13532.9 16212.2 16386.8 16405.1 16410.4 16411.4 5585.4 6759.6 6761.0 6903.2 6912.3 1018.8 1044.9 1058.6 1108.3 1119.1 31378.4 31992.2 31994.2 32247.2 32641.0 1153.3 1185.7 1253.1 1253.2 1278.1 16895.6 16952.7 16965.9 17204.5 17217.5 467085.0 470659.0 470925.0 473250.0 474269.0 56299.4 56417.0 56424.4 56444.9 56464.0 1290.6 1377.3 1410.7 1423.0 1428.4 31240.8 32352.8 33268.3 33290.3 33468.0 48297.3 48652.0 48966.8 49049.0 49118.3 371084.0 371759.0 373026.0 373221.0 373588.0 34786.5 39105.2 39749.4 39924.4 43144.8 47517.8 47710.8 47788.3 48310.8 48596.5 40215.8 40919.8 41131.2 41933.8 42154.8 40606.8 40632.0 40646.4 41390.4 41452.6 40313.6 40939.0 41026.6 41269.6 41370.4 35200.5 35419.3 35905.5 36135.0 36149.6 27994.4 28022.0 28222.6 28281.3 28334.7 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | [#2] jquery.html Cycles:180 Average:28804.13 Median:1496.58 stddev:82726.00 (5527.7%) stddev-sans-first:82930.54 06:05:20 INFO - PROCESS | 13399 | Values: 379.5 381.8 391.4 396.2 401.0 17369.1 18830.5 19161.3 19178.9 19180.0 17610.1 18090.5 18549.4 19300.8 19341.0 5196.4 5292.9 5324.8 5372.6 5378.3 5172.7 5250.2 5252.9 5289.9 5298.2 5290.9 5307.6 5307.6 5309.6 5579.4 5552.9 5717.3 5738.2 5739.8 5741.9 5019.7 5152.9 5157.6 5157.7 5343.0 13473.0 13609.6 13687.9 13721.7 19845.2 2143.9 2249.5 2250.2 2580.7 2613.7 9705.7 10139.5 10342.9 12626.4 13292.2 1646.9 1740.9 1879.2 1941.4 1988.0 51030.7 82727.2 82976.6 83190.0 83720.6 10843.3 11363.3 11411.1 11549.9 11970.8 15166.3 15284.1 15362.5 15382.1 15397.2 7868.5 8216.4 8337.5 8356.5 8652.3 7691.7 7716.6 7734.3 7868.3 8007.4 4681.1 4788.4 4808.6 4921.3 4924.8 1964.1 1982.8 1984.8 1994.4 2024.1 899.0 950.2 952.1 1004.6 1024.2 17938.4 18941.4 19218.4 19303.6 19439.4 940.5 980.6 1002.0 1024.3 1057.7 10333.7 10666.9 10677.5 10732.7 10747.9 490381.6 491960.0 497246.0 502612.3 529280.0 53296.7 92503.5 93199.5 94871.5 95211.0 995.7 1023.9 1034.8 1042.1 1052.7 17537.0 17775.6 17852.3 18024.5 18051.6 41338.4 41658.8 42385.2 42803.6 43553.1 58246.1 59077.7 59232.4 59576.7 60846.0 7285.8 7778.0 8147.5 8699.4 8984.4 9694.8 13274.0 13535.8 13648.9 13712.8 11280.9 11700.9 11783.6 11965.5 11988.0 8100.8 11162.9 11601.7 11669.6 11798.7 11897.9 12104.6 12154.4 12166.9 12184.5 8080.9 8100.9 8121.3 8204.8 8277.9 11279.0 11499.7 11639.9 11768.9 12058.2 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | [#3] mootools.html Cycles:180 Average:5784.14 Median:2562.51 stddev:3257.72 (127.1%) stddev-sans-first:3249.75 06:05:20 INFO - PROCESS | 13399 | Values: 1341.1 1509.7 1536.5 1721.7 1858.7 8135.8 8483.5 8515.6 8706.8 8726.7 8034.4 8265.2 8463.3 8474.7 8476.0 2007.0 2293.9 2361.4 2367.5 2411.2 2409.5 2411.7 2413.6 2419.2 2420.2 2297.8 2354.5 2358.3 2359.8 2362.2 1992.0 2309.1 2349.8 2379.3 2387.7 2307.8 2321.4 2328.1 2328.7 2364.6 8484.1 8574.1 8599.7 8610.8 8612.0 5303.3 5424.1 5426.8 5438.2 5442.7 3400.4 3482.6 3528.1 3538.6 3580.5 1099.5 1110.9 1118.8 1140.3 1161.1 11907.5 12154.8 12177.1 12196.8 12202.1 4658.7 4704.3 4783.8 4836.6 4890.6 10443.8 10465.3 10475.1 10483.4 10487.8 4635.0 5370.7 5390.4 5397.9 5399.4 4902.3 5027.4 5041.3 5066.6 5071.8 3970.0 4026.8 4056.5 4177.4 4184.0 1277.3 1328.0 1328.5 1343.5 1347.9 1013.9 1015.9 1020.4 1024.5 1030.2 6081.9 6135.2 6210.4 6225.4 6278.0 465.2 466.4 468.4 473.4 482.1 4254.7 4280.3 4282.4 4283.6 4290.7 10458.8 10964.1 10977.7 11004.9 11014.1 10596.9 10842.2 10867.1 10872.6 10995.1 7896.8 7941.7 7996.5 8035.6 8044.3 5828.9 5851.2 5933.6 6065.2 6115.0 9951.9 9971.1 10129.1 10188.4 10197.9 10031.8 10306.3 10316.2 10370.9 10408.2 6843.0 7324.7 7655.5 7790.2 7862.3 8332.5 8377.1 8413.3 8458.8 8465.0 7317.6 7487.2 7502.8 7599.7 7604.9 7426.4 7477.4 7509.9 7533.9 7576.7 7035.4 7058.8 7091.5 7096.2 7097.0 5026.5 5097.4 5143.5 5229.4 5259.8 5190.5 5315.7 5342.9 5382.4 5409.2 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | [#4] prototype.html Cycles:180 Average:4474.74 Median:4970.15 stddev:1253.15 (25.2%) stddev-sans-first:1217.49 06:05:20 INFO - PROCESS | 13399 | Values: 332.7 337.1 342.2 342.6 346.4 5415.3 5426.8 5432.7 5484.5 5536.9 5311.3 5355.4 5373.4 5379.2 5457.8 3768.8 4026.4 4060.8 4131.4 4142.2 4156.4 4209.6 4221.3 4226.6 4240.3 4143.0 4167.7 4173.7 4182.3 4200.7 3976.1 4123.3 4141.3 4170.2 4179.5 3691.9 3698.0 3712.6 3716.0 3732.6 5177.8 5179.6 5193.8 5195.5 5215.7 4715.2 4743.3 4773.9 4790.3 4793.9 5599.6 5606.4 5616.2 5618.2 5631.4 4076.6 4099.6 4100.0 4106.0 4107.2 5721.8 5747.8 5809.0 5832.3 5871.8 4117.6 4172.6 4186.7 4190.4 4190.7 4238.2 4255.0 4256.7 4271.0 4275.3 4698.3 4782.5 4829.3 4848.7 4877.3 5682.9 5686.2 5693.2 5698.1 5699.3 1463.2 1463.3 1477.3 1491.1 1493.6 916.8 951.9 958.6 967.4 968.3 4712.8 4798.8 4812.5 4853.2 4853.7 5135.7 5150.0 5152.6 5167.5 5174.9 4556.8 4797.4 4821.2 4848.6 4853.0 2745.3 2776.3 2778.5 2782.0 2818.6 5272.3 5286.2 5293.4 5308.9 5317.7 4925.5 5263.9 5280.0 5353.5 5354.7 5346.7 5349.3 5353.3 5357.8 5394.4 5133.5 5169.8 5226.6 5230.5 5279.4 4854.9 5119.9 5126.9 5159.4 5191.7 5215.2 5273.7 5290.4 5300.7 5307.3 3826.8 3845.2 3846.3 3861.8 3870.5 5017.8 5110.8 5120.8 5133.7 5188.4 5026.3 5039.7 5041.2 5053.2 5087.3 4945.8 4966.5 4973.8 5010.8 5043.6 4596.9 4903.2 4907.7 4931.0 4974.9 5025.2 5041.9 5064.1 5068.4 5107.3 4813.5 4942.1 4966.0 4979.2 5003.4 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - PROCESS | 13399 | [#5] yui.html Cycles:180 Average:18945.64 Median:25886.49 stddev:25312.97 (97.8%) stddev-sans-first:25346.80 06:05:20 INFO - PROCESS | 13399 | Values: 674.5 716.5 732.9 798.8 846.6 14569.3 15780.9 16079.8 16085.9 16095.4 14847.1 15003.8 15013.8 15022.9 15291.9 816.1 818.1 823.0 823.4 824.1 812.9 813.7 814.2 814.9 815.7 863.6 867.4 869.5 869.6 869.7 857.9 860.8 861.8 861.9 862.0 619.9 624.6 626.6 627.8 628.0 14577.6 14696.3 14696.9 14814.6 14831.4 12467.9 13285.1 13584.3 13595.4 13673.6 12660.0 12825.5 13110.7 13120.8 13248.5 4527.4 4539.5 4550.6 4550.8 4555.1 62246.7 63860.4 64204.4 72355.0 72387.6 11568.8 11655.8 11681.6 11831.3 12086.9 21401.3 21417.1 21421.3 21428.0 21455.6 8356.0 8370.8 8842.5 8931.6 9145.0 4588.3 4627.0 4818.8 4932.9 5003.0 3615.7 3679.6 3718.8 3719.0 3722.9 586.1 588.7 595.2 598.8 613.9 604.4 606.9 636.4 675.6 696.2 8900.5 9110.4 9131.5 9153.3 9498.4 510.5 633.1 640.6 680.2 686.5 4247.8 4470.4 4504.1 4552.0 4683.3 66728.0 67333.7 67438.7 67523.7 75533.2 79311.0 89818.6 94024.5 94501.0 96854.5 84317.0 84646.6 85232.0 85914.0 86002.6 8963.0 9116.2 9220.6 9376.7 9467.6 51262.5 51426.2 51583.2 52657.2 52734.2 67745.7 68108.6 73765.0 74399.3 76076.6 12012.2 12620.9 12798.7 13028.5 13583.6 14324.6 14717.9 15364.5 15520.1 15698.5 12878.1 13229.1 13261.1 13265.5 13303.3 13773.7 13902.2 14018.8 14223.9 14905.3 12153.5 12539.6 12632.7 12775.0 12805.2 10620.7 10801.4 10959.5 10968.1 11239.5 10633.7 10867.4 11176.3 11216.5 11217.7 06:05:20 INFO - PROCESS | 13399 | -------- Summary: end -------- 06:05:20 INFO - PROCESS | 13399 | 06:05:20 INFO - TEST-INFO | 13399: exit 0 06:05:20 INFO - TEST-OK | dromaeo_css | took 1111598ms 06:05:20 INFO - TEST-START | kraken 06:05:20 INFO - operating with platform_type : linux_ 06:05:20 INFO - Initialising browser for kraken test... 06:05:20 INFO - TEST-INFO | started process 13582 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeHiWgs/profile http://localhost:40353/getInfo.html) 06:05:24 INFO - PROCESS | 13582 | __metrics Screen width/height:1600/1200 06:05:24 INFO - PROCESS | 13582 | colorDepth:24 06:05:24 INFO - PROCESS | 13582 | Browser inner width/height: 1024/697 06:05:24 INFO - PROCESS | 13582 | __metrics 06:05:25 ERROR - PROCESS | 13582 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:05:26 INFO - TEST-INFO | 13582: exit 0 06:05:26 INFO - Browser initialized. 06:05:26 INFO - Running cycle 1/1 for kraken test... 06:05:26 INFO - TEST-INFO | started process 13666 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeHiWgs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:05:38 INFO - PROCESS | 13666 | RSS: Main: 156729344 06:05:38 INFO - PROCESS | 13666 | 06:05:40 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40353/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:41 INFO - PROCESS | 13666 | RSS: Main: 214650880 06:05:41 INFO - PROCESS | 13666 | 06:05:44 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40353/tests/kraken/driver.html?testName=audio-dft) 06:05:44 INFO - PROCESS | 13666 | RSS: Main: 200720384 06:05:44 INFO - PROCESS | 13666 | 06:05:47 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40353/tests/kraken/driver.html?testName=audio-fft) 06:05:48 INFO - PROCESS | 13666 | RSS: Main: 267464704 06:05:48 INFO - PROCESS | 13666 | 06:05:50 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40353/tests/kraken/driver.html?testName=audio-oscillator) 06:05:51 INFO - PROCESS | 13666 | RSS: Main: 179580928 06:05:51 INFO - PROCESS | 13666 | 06:05:53 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40353/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:53 INFO - PROCESS | 13666 | RSS: Main: 183447552 06:05:53 INFO - PROCESS | 13666 | 06:05:56 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40353/tests/kraken/driver.html?testName=imaging-darkroom) 06:05:57 INFO - PROCESS | 13666 | RSS: Main: 218284032 06:05:57 INFO - PROCESS | 13666 | 06:06:00 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40353/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:01 INFO - PROCESS | 13666 | RSS: Main: 220585984 06:06:01 INFO - PROCESS | 13666 | 06:06:04 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40353/tests/kraken/driver.html?testName=json-parse-financial) 06:06:04 INFO - PROCESS | 13666 | RSS: Main: 220626944 06:06:04 INFO - PROCESS | 13666 | 06:06:06 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40353/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:07 INFO - PROCESS | 13666 | RSS: Main: 204722176 06:06:07 INFO - PROCESS | 13666 | 06:06:08 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:09 INFO - PROCESS | 13666 | RSS: Main: 209276928 06:06:09 INFO - PROCESS | 13666 | 06:06:11 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:12 INFO - PROCESS | 13666 | RSS: Main: 216690688 06:06:12 INFO - PROCESS | 13666 | 06:06:14 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:15 INFO - PROCESS | 13666 | RSS: Main: 223006720 06:06:15 INFO - PROCESS | 13666 | 06:06:17 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:18 INFO - PROCESS | 13666 | RSS: Main: 220246016 06:06:18 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | Cycle 1(1): loaded http://localhost:40353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40353/tests/kraken/driver.html?testName=ai-astar) 06:06:20 INFO - PROCESS | 13666 | RSS: Main: 263495680 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | __start_tp_report 06:06:20 INFO - PROCESS | 13666 | _x_x_mozilla_page_load 06:06:20 INFO - PROCESS | 13666 | _x_x_mozilla_page_load_details 06:06:20 INFO - PROCESS | 13666 | |i|pagename|runs| 06:06:20 INFO - PROCESS | 13666 | |0;ai-astar;111;105;110;111;110;109;109;110;108;109 06:06:20 INFO - PROCESS | 13666 | |1;audio-beat-detection;150;124;133;178;127;126;154;128;175;133 06:06:20 INFO - PROCESS | 13666 | |2;audio-dft;160;162;159;161;161;160;160;162;161;160 06:06:20 INFO - PROCESS | 13666 | |3;audio-fft;75;74;74;127;75;76;74;74;104;76 06:06:20 INFO - PROCESS | 13666 | |4;audio-oscillator;83;85;125;84;84;85;120;84;86;125 06:06:20 INFO - PROCESS | 13666 | |5;imaging-gaussian-blur;116;118;117;116;117;117;118;117;116;117 06:06:20 INFO - PROCESS | 13666 | |6;imaging-darkroom;145;146;145;146;146;145;146;145;145;145 06:06:20 INFO - PROCESS | 13666 | |7;imaging-desaturate;96;94;95;95;95;95;94;94;95;95 06:06:20 INFO - PROCESS | 13666 | |8;json-parse-financial;55;56;57;57;56;57;57;55;56;55 06:06:20 INFO - PROCESS | 13666 | |9;json-stringify-tinderbox;61;61;61;61;62;62;61;61;61;61 06:06:20 INFO - PROCESS | 13666 | |10;stanford-crypto-aes;83;79;79;79;82;86;84;82;116;83 06:06:20 INFO - PROCESS | 13666 | |11;stanford-crypto-ccm;121;120;119;119;121;128;127;123;140;125 06:06:20 INFO - PROCESS | 13666 | |12;stanford-crypto-pbkdf2;165;163;164;163;162;164;162;167;159;161 06:06:20 INFO - PROCESS | 13666 | |13;stanford-crypto-sha256-iterative;63;66;63;62;62;68;64;63;63;67 06:06:20 INFO - PROCESS | 13666 | __end_tp_report 06:06:20 INFO - PROCESS | 13666 | __start_cc_report 06:06:20 INFO - PROCESS | 13666 | _x_x_mozilla_cycle_collect,1586 06:06:20 INFO - PROCESS | 13666 | __end_cc_report 06:06:20 INFO - PROCESS | 13666 | __startTimestamp1464872780480__endTimestamp 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | ------- Summary: start ------- 06:06:20 INFO - PROCESS | 13666 | Number of tests: 14 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:1.75 (1.6%) stddev-sans-first:1.73 06:06:20 INFO - PROCESS | 13666 | Values: 111.0 105.0 110.0 111.0 110.0 109.0 109.0 110.0 108.0 109.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:141.50 stddev:20.41 (14.4%) stddev-sans-first:21.48 06:06:20 INFO - PROCESS | 13666 | Values: 150.0 124.0 133.0 178.0 127.0 126.0 154.0 128.0 175.0 133.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 06:06:20 INFO - PROCESS | 13666 | Values: 160.0 162.0 159.0 161.0 161.0 160.0 160.0 162.0 161.0 160.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#3] audio-fft Cycles:10 Average:82.90 Median:74.50 stddev:18.03 (24.2%) stddev-sans-first:18.90 06:06:20 INFO - PROCESS | 13666 | Values: 75.0 74.0 74.0 127.0 75.0 76.0 74.0 74.0 104.0 76.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:84.00 stddev:18.86 (22.5%) stddev-sans-first:19.40 06:06:20 INFO - PROCESS | 13666 | Values: 83.0 85.0 125.0 84.0 84.0 85.0 120.0 84.0 86.0 125.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 06:06:20 INFO - PROCESS | 13666 | Values: 116.0 118.0 117.0 116.0 117.0 117.0 118.0 117.0 116.0 117.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 06:06:20 INFO - PROCESS | 13666 | Values: 145.0 146.0 145.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 06:06:20 INFO - PROCESS | 13666 | Values: 96.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.50 stddev:0.88 (1.5%) stddev-sans-first:0.83 06:06:20 INFO - PROCESS | 13666 | Values: 55.0 56.0 57.0 57.0 56.0 57.0 57.0 55.0 56.0 55.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 06:06:20 INFO - PROCESS | 13666 | Values: 61.0 61.0 61.0 61.0 62.0 62.0 61.0 61.0 61.0 61.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:82.50 stddev:11.04 (13.4%) stddev-sans-first:11.67 06:06:20 INFO - PROCESS | 13666 | Values: 83.0 79.0 79.0 79.0 82.0 86.0 84.0 82.0 116.0 83.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:124.00 stddev:6.38 (5.1%) stddev-sans-first:6.65 06:06:20 INFO - PROCESS | 13666 | Values: 121.0 120.0 119.0 119.0 121.0 128.0 127.0 123.0 140.0 125.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.00 Median:163.50 stddev:2.21 (1.4%) stddev-sans-first:2.22 06:06:20 INFO - PROCESS | 13666 | Values: 165.0 163.0 164.0 163.0 162.0 164.0 162.0 167.0 159.0 161.0 06:06:20 INFO - PROCESS | 13666 | 06:06:20 INFO - PROCESS | 13666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:63.50 stddev:2.13 (3.4%) stddev-sans-first:2.22 06:06:20 INFO - PROCESS | 13666 | Values: 63.0 66.0 63.0 62.0 62.0 68.0 64.0 63.0 63.0 67.0 06:06:20 INFO - PROCESS | 13666 | -------- Summary: end -------- 06:06:20 INFO - PROCESS | 13666 | 06:06:21 INFO - TEST-INFO | 13666: exit 0 06:06:21 INFO - TEST-OK | kraken | took 60463ms 06:06:21 INFO - SUITE-END | took 1172s 06:06:21 INFO - Completed test suite (00:19:32) 06:06:21 INFO - javascript benchmark 06:06:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.6079240094987, 3148.9842550787243, 3163.6361818390883, 3164.620245570532, 3204.0868352519524, 5383.380911195214, 5400.864978375543, 5448.45515448455, 5477.315335583052, 5514.696691681956, 5429.185622156679, 5461.563074954004, 5479.726013699319, 5484.533814625762, 5516.779328826842, 4885.267209918514, 4886.071646387175, 5023.824166154185, 5069.2649565812935, 5129.9230511542355, 5036.093503169428, 5060.569851562622, 5084.3644544431945, 5089.872753181163, 5095.923561146586, 4845.200541910581, 4871.82948596801, 4882.682625629358, 4954.702717836905, 4993.6254780891395, 4966.528179822911, 4976.975115124401, 4978.6514943953935, 4985.501449855022, 5034.84895453137, 4913.606911447076, 4944.9368385797225, 4952.87617809554, 4958.033183529214, 4960.355153830015, 5247.160454327328, 5257.579393648499, 5340.706261155635, 5361.08861493547, 5456.863578410532, 5807.390224026501, 5831.9416805831825, 5840.211571437844, 5911.852203694899, 5928.318243402021, 5987.760489580412, 5990.9101363479585, 5998.130271110665, 6007.789727359522, 6008.098785182214, 5311.203319502067, 5458.153986132156, 5482.479164479368, 5506.476884695948, 5565.248691426606, 6052.700752371906, 6059.791268323752, 6065.975194600972, 6068.601583113421, 6074.179985701962, 5578.665280083208, 5774.740136693859, 5817.592768506249, 5830.3586605473365, 5830.7084645767545, 5816.738246778905, 5878.647281163144, 5883.558733095035, 5898.557608179403, 5905.291365036223, 5818.738156782955, 5855.2973643163095, 5867.853303667443, 5873.383294754075, 5881.029630111069, 5634.5210657093785, 5728.885422291614, 5847.44449277318, 5879.353271140171, 5901.793437229677, 4393.209222339946, 4405.691601587858, 4407.162639098561, 4452.220861349251, 4457.175422546839, 3901.8439262429183, 3913.0, 3925.3523168676847, 3927.8232479538487, 3955.4462375267485, 5308.150695888639, 5361.490658387447, 5426.403095659474, 5471.37079235893, 5505.449455054462, 5871.442212989845, 5911.852203694942, 5946.940530594638, 5953.493953014043, 6000.609960352563, 5504.4495550444635, 5510.8071217507195, 5524.723763811793, 5526.309211348582, 5533.944660553343, 4895.755212239374, 5000.000000000015, 5053.393592768891, 5060.493950604984, 5062.468440813662, 6061.7575296987625, 6071.058985857199, 6073.544484163705, 6081.209442772411, 6081.57428979976, 5929.436703513159, 5935.970320148372, 5937.703114844327, 5940.762369505172, 5942.435468630473, 5886.0, 5938.465538101592, 5951.7619295227705, 5966.8209953699725, 5983.431574000463, 5731.856703582277, 5735.139729040678, 5735.512481439125, 5735.885282294247, 5737.655740655574, 5714.885702286014, 5724.370319264961, 5863.824085277449, 5868.4424979626865, 5874.94125058744, 5673.092305231308, 5685.573581981451, 5714.514266287412, 5752.28096487939, 5763.221965034667, 5714.5999780015, 5727.8568035799435, 5729.427057294237, 5732.742026608896, 5735.426457354231, 5427.48438898304, 5547.611667183259, 5615.915761263503, 5621.8594535136945, 5636.689982051026, 5694.629849059798, 5825.213596164633, 5826.592138550431, 5839.970800146142, 5841.678707671119, 5607.971960140173, 5634.830955071355, 5656.575756818337, 5657.236273103078, 5697.914531281951], "value": 5387.74329752764, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.8746563221723, 613.1905884232813, 626.7273735924875, 638.84348334658, 646.0728854094372, 33499.33001339975, 43762.0, 44031.33952990704, 44221.778891105496, 47613.0, 44093.33859992103, 44631.77684111583, 44722.77638611811, 44737.0, 44756.10487790242, 7995.760127196193, 8727.865377500932, 8929.687460938869, 8934.821303573925, 10369.637062702806, 10158.492075396238, 10573.841392379121, 10614.57541698331, 10651.30766500175, 10653.041226289632, 10666.733331666694, 10724.78550428991, 10739.0, 10740.838887416696, 10741.677749667484, 8030.07654119772, 10330.535125919352, 10567.682969510888, 10598.152147828156, 10599.894001059967, 10543.156547476183, 10611.204159687977, 10634.468276586216, 10637.414942178177, 10642.361458312525, 41008.794956025326, 41308.586914131076, 41372.172556549005, 41401.585984140074, 41488.79255603703, 3497.562804649419, 3552.3960926642535, 3561.3233485637907, 3738.028112690693, 4121.752694838289, 31793.6820631793, 32137.357252855047, 32344.0, 32355.352892942246, 32379.838100809582, 4260.68044896634, 4287.464066991626, 4411.6250118739945, 4447.3996010538485, 4454.153710794939, 242453.0, 243588.0, 243649.0, 243663.0, 244079.0, 50038.74980625074, 54801.45198548043, 55614.721926390106, 55666.44333556694, 60317.0, 59818.0, 60011.69994150001, 60128.0, 60146.39853601495, 60253.69873150606, 15936.840631593768, 15980.600484987968, 16016.599585010468, 16022.519324420286, 16049.839501604834, 13108.344582770824, 13184.274864882524, 13214.339283036003, 13524.256165910772, 13532.864671353358, 16212.189390530426, 16386.75419868703, 16405.097719625534, 16410.425635102714, 16411.42560010394, 5585.357683866326, 6759.628220447824, 6760.977218554952, 6903.1716194057035, 6912.343327383891, 1018.8267994440963, 1044.9373037617768, 1058.5501162006117, 1108.2685427617735, 1119.1103073056777, 31378.37243255122, 31992.200194995312, 31994.200144996095, 32247.193820154214, 32641.0, 1153.2561497834017, 1185.7035741064733, 1253.1040306181123, 1253.2355263289387, 1278.111712359901, 16895.577610559834, 16952.74570881438, 16965.91517042407, 17204.483865484057, 17217.483475495756, 467085.0, 470659.0, 470925.0, 473250.0, 474269.0, 56299.437005630236, 56417.0, 56424.43575564192, 56444.871102577716, 56464.0, 1290.5805613175755, 1377.3113443278362, 1410.7178564286978, 1423.0394483723653, 1428.4286285485682, 31240.843795780875, 32352.83823580867, 33268.33463330672, 33290.334193316485, 33468.0, 48297.27554086762, 48652.0, 48966.79649415856, 49049.0, 49118.26322605092, 371084.0, 371759.0, 373026.0, 373221.0, 373588.0, 34786.478202827486, 39105.217895641355, 39749.403758944216, 39924.401133982436, 43144.78427607968, 47517.76241118772, 47710.76144619255, 47788.2831757517, 48310.75844620755, 48596.514034860615, 40215.79892100521, 40919.795401022806, 41131.1773764529, 41933.79033104815, 42154.7892260549, 40606.79696601498, 40632.0, 40646.39030414487, 41390.37914431225, 41452.58547414608, 40313.59686403216, 40939.0, 41026.58973410228, 41269.58730412657, 41370.379444308965, 35200.47199292064, 35419.29161416809, 35905.46141807823, 36135.0, 36149.638503614624, 27994.44011119807, 28022.0, 28222.576661349685, 28281.292967675974, 28334.71665283321], "value": 20243.117829151575, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.4952712891853, 381.78429187509056, 391.39334032250014, 396.2451529835664, 401.0335092427251, 17369.131543422827, 18830.529236769053, 19161.329353472633, 19178.90410547947, 19180.0, 17610.11949402528, 18090.4572862814, 18549.35077272296, 19300.806991930112, 19341.0, 5196.350456192976, 5292.947070529303, 5324.840254792362, 5372.597055220855, 5378.327709036371, 5172.741362931857, 5250.212468129792, 5252.894942101156, 5289.920651190235, 5298.178782288752, 5290.920636190441, 5307.575393968474, 5307.575393968493, 5309.601779866506, 5579.38626751057, 5552.944470555303, 5717.313922329306, 5738.167965644979, 5739.7991070312755, 5741.942580574183, 5019.723915184663, 5152.896942061176, 5157.613179011551, 5157.664751791159, 5343.0, 13472.989525785508, 13609.591712248648, 13687.931560342235, 13721.725565488734, 19845.206191752313, 2143.8928053597415, 2249.5163539839014, 2250.1561914282142, 2580.6516120323704, 2613.6732908386452, 9705.708828735149, 10139.54372053252, 10342.896571034269, 12626.368681565977, 13292.220967306743, 1646.8517833395051, 1740.8694347923956, 1879.1637721213929, 1941.4466876940146, 1987.9602407951904, 51030.744846275906, 82727.17272827255, 82976.58511707463, 83190.0, 83720.58139709262, 10843.349399036084, 11363.261388009918, 11411.087113030937, 11549.942250288863, 11970.82043769344, 15166.31751571182, 15284.082955022512, 15362.539123826304, 15382.077075375513, 15397.230138493254, 7868.527888326605, 8216.351352691949, 8337.458065225741, 8356.456830306011, 8652.307815374756, 7691.653875575613, 7716.575588342583, 7734.342580880688, 7868.291853733077, 8007.439479236514, 4681.063787242565, 4788.353572267699, 4808.567228949411, 4921.309637771363, 4924.803007879645, 1964.0768838645813, 1982.7521559805025, 1984.781674015857, 1994.4415563642203, 2024.0588126521238, 898.9730308090768, 950.1543626172712, 952.1478276942098, 1004.638330201127, 1024.2164743970868, 17938.372156974445, 18941.43175704731, 19218.4234472966, 19303.613927721366, 19439.416817495498, 940.4968341937031, 980.612658000086, 1001.9760084629008, 1024.283001898674, 1057.7144171073921, 10333.741656458498, 10666.893331066745, 10677.519511621995, 10732.731681707864, 10747.946260268649, 490381.5969688164, 491960.0, 497246.0, 502612.3040771957, 529280.0, 53296.73351633217, 92503.53748231215, 93199.53400233091, 94871.52564237273, 95211.0, 995.6515219673201, 1023.9385636861663, 1034.7516596017051, 1042.0777611813598, 1052.736815796051, 17537.0, 17775.644487109927, 17852.285908563514, 18024.549386264924, 18051.63896722032, 41338.379924301764, 41658.79170604127, 42385.152296954504, 42803.57196427996, 43553.12893742044, 58246.12630810626, 59077.70461147667, 59232.40767592269, 59576.70211648914, 60846.0, 7285.8178545534665, 7778.0, 8147.470414423044, 8699.391042626956, 8984.371094023356, 9694.806103878023, 13274.027037280879, 13535.79696304576, 13648.863511365156, 13712.794308085187, 11280.943595281971, 11700.941495292469, 11783.587574434854, 11965.521379144737, 11988.0, 8100.825380319939, 11162.944185279022, 11601.709957251136, 11669.649910502698, 11798.705032373917, 11897.881021189678, 12104.576339828063, 12154.392280386122, 12166.878331216929, 12184.512619495476, 8080.878786818085, 8100.87848682282, 8121.309688676529, 8204.794880128045, 8277.875831862648, 11279.0, 11499.712507187387, 11639.941800290944, 11768.882311176778, 12058.15592908488], "value": 8990.734216268798, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.1349679456744, 1509.713154500645, 1536.508317338452, 1721.681488924549, 1858.6933156029256, 8135.755927322182, 8483.490990540564, 8515.574221288942, 8706.825863482727, 8726.738197854074, 8034.357251419887, 8265.214804593554, 8463.280621147194, 8474.661013559451, 8475.95762021189, 2007.046652839904, 2293.8623682579014, 2361.386039629696, 2367.5264947010583, 2411.1801987324307, 2409.494006258679, 2411.686480757499, 2413.613821788514, 2419.1532963462823, 2420.1529464687396, 2297.7587353327826, 2354.470244195051, 2358.280724379072, 2359.7876191142796, 2362.244081893795, 1991.9701204481944, 2309.1109922679807, 2349.835511514195, 2379.298107058422, 2387.7492863249454, 2307.8038366738847, 2321.419645088728, 2328.0571368595747, 2328.6507023946374, 2364.5625559271416, 8484.109168537338, 8574.099719529484, 8599.656013759442, 8610.78473038179, 8612.0, 5303.257543943851, 5424.05079111154, 5426.8371948841595, 5438.211459338413, 5442.673439593597, 3400.387930172568, 3482.6343233960574, 3528.1179705073732, 3538.610752817188, 3580.5166302549083, 1099.5217080569898, 1110.9222354435192, 1118.815395459748, 1140.2645293785463, 1161.134954458925, 11907.464164112549, 12154.756904861853, 12177.147599667938, 12196.756064878742, 12202.084843636763, 4658.650601204856, 4704.341392205094, 4783.832565860178, 4836.613070954315, 4890.559849613481, 10443.791124177475, 10465.267431279737, 10475.109615682752, 10483.423411712429, 10487.842682359771, 4634.976825115853, 5370.677759334452, 5390.407055223922, 5397.865053373618, 5399.37907140676, 4902.313676085351, 5027.4469808321055, 5041.269015992661, 5066.645334826527, 5071.7971281149075, 3970.024087786609, 4026.838926442968, 4056.5334986476932, 4177.394277829759, 4183.958160418357, 1277.3294020639053, 1327.9615789762668, 1328.548293580168, 1343.543195313598, 1347.9123856949268, 1013.8732658417698, 1015.8577799108131, 1020.3673722292226, 1024.533837104116, 1030.1655658916302, 6081.908771368433, 6135.202423684982, 6210.441060304594, 6225.3774622538285, 6278.0, 465.16038550416823, 466.3634139399695, 468.40278644728244, 473.3704173449304, 482.1441940555472, 4254.680898932593, 4280.293751530962, 4282.400463935051, 4283.635890949242, 4290.678199135077, 10458.790824183627, 10964.122870170368, 10977.725556861142, 11004.889951100547, 11014.118870490342, 10596.947015264874, 10842.186835987333, 10867.130629549618, 10872.619458319079, 10995.120390368751, 7896.842063158705, 7941.722039728582, 7996.480228785111, 8035.638396272182, 8044.2760151586635, 5828.930729590934, 5851.21008663842, 5933.584649074523, 6065.211522502223, 6114.969425152845, 9951.850722239027, 9971.102600765676, 10129.088382045651, 10188.388696678223, 10197.9490102549, 10031.849522257025, 10306.33008854422, 10316.226283028953, 10370.896291036994, 10408.167346612405, 6843.0, 7324.707011719472, 7655.50239234448, 7790.220977902164, 7862.253085956825, 8332.458390204618, 8377.078521362768, 8413.3269338454, 8458.788530286545, 8465.0, 7317.597532135784, 7487.2138425464545, 7502.774916752506, 7599.734009309647, 7604.923950760422, 7426.443016773872, 7477.439192060726, 7509.88735168962, 7533.924660753322, 7576.6969321228735, 7035.366816986496, 7058.788236352918, 7091.503594748525, 7096.1839388468925, 7097.0, 5026.52248036436, 5097.362829646294, 5143.459936706592, 5229.424763276113, 5259.815906443409, 5190.506901844319, 5315.681059136464, 5342.8931421372135, 5382.381026181883, 5409.242706021081], "value": 4582.881158190169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.70908873570545, 337.1302040734904, 342.218031797343, 342.57520674363786, 346.3920818962723, 5415.296011518507, 5426.8371948841495, 5432.70120143392, 5484.506394424501, 5536.9446305536985, 5311.282976798131, 5355.384130824957, 5373.382061062979, 5379.246905433242, 5457.772001299705, 3768.8304026318883, 4026.4362989181463, 4060.8375664973514, 4131.421600975866, 4142.212979533894, 4156.397322388267, 4209.6421804146685, 4221.26127927613, 4226.577342265764, 4240.342746874239, 4142.958570414303, 4167.666586673074, 4173.686973476986, 4182.33082706767, 4200.684948628865, 3976.125252444472, 4123.257813593552, 4141.343462614963, 4170.207660544487, 4179.477565304337, 3691.9261614767556, 3698.0, 3712.554493460775, 3716.0338312038793, 3732.5520937487677, 5177.818776342848, 5179.627398739337, 5193.844184674465, 5195.532402083831, 5215.687058776485, 4715.1512727709, 4743.294449810556, 4773.928391074137, 4790.257510085942, 4793.904121917542, 5599.636023658449, 5606.383297837235, 5616.241807355996, 5618.2415373924405, 5631.380548139701, 4076.6331030207425, 4099.560761346994, 4100.036491424513, 4106.0, 4107.199096176247, 5721.799737009226, 5747.82756517301, 5808.97095514524, 5832.300123985064, 5871.765129394819, 4117.649999749995, 4172.603602657743, 4186.748795072305, 4190.434291370687, 4190.748555086643, 4238.19474299882, 4254.957450425456, 4256.7445953242905, 4271.0, 4275.273203555403, 4698.342232087511, 4782.521747825259, 4829.275608658659, 4848.733319667452, 4877.268409738567, 5682.943170568324, 5686.2039314496005, 5693.231413759172, 5698.088305871041, 5699.287589051369, 1463.239115659851, 1463.3488097796592, 1477.3351991603822, 1491.1351416178807, 1493.6041948883556, 916.8166366726548, 951.8572214167792, 958.6309270930742, 967.3905439573024, 968.3221744778683, 4712.764361781966, 4798.760061996886, 4812.470628230892, 4853.247746599212, 4853.708777473363, 5135.717535535581, 5149.957283532409, 5152.639315247971, 5167.534921856975, 5174.8965020700125, 4556.7721613919175, 4797.376341075638, 4821.216150024443, 4848.5636292733825, 4853.015395531793, 2745.272502786763, 2776.3059235191204, 2778.5415406458114, 2781.9721802781714, 2818.647049416292, 5272.260154149121, 5286.233496143038, 5293.364796224478, 5308.893822123536, 5317.73411329432, 4925.458199598042, 5263.868268322329, 5280.0, 5353.544948679328, 5354.705491197944, 5346.732663366816, 5349.251104845324, 5353.330833645794, 5357.8392648220615, 5394.406615272317, 5133.460986596503, 5169.793208271627, 5226.555742761908, 5230.495567969546, 5279.366476022902, 4854.927176092291, 5119.897602048012, 5126.923096153635, 5159.355080614923, 5191.662541934762, 5215.243789650555, 5273.70794155419, 5290.418054014132, 5300.70846103468, 5307.310049693515, 3826.789526576065, 3845.192509573021, 3846.288401661234, 3861.8455261789213, 3870.477485539416, 5017.8243761468175, 5110.821121260738, 5120.771014956458, 5133.6919784813035, 5188.429272780067, 5026.346574945233, 5039.722815245197, 5041.2438134280155, 5053.191489361684, 5087.313212716236, 4945.777440015136, 4966.478519755374, 4973.825916092919, 5010.797408621832, 5043.604287963188, 4596.88507787308, 4903.156169421798, 4907.68100073494, 4931.013797240638, 4974.8756281093265, 5025.246213068069, 5041.873953151201, 5064.113780088396, 5068.391792984866, 5107.284980102714, 4813.518648135158, 4942.084170339517, 4965.975170124127, 4979.1618777298045, 5003.374578177728], "value": 4085.1568367047453, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.4873895839161, 716.4554938246933, 732.9340359367656, 798.7603718884335, 846.5978660136434, 14569.271536423175, 15780.921095394522, 16079.83920160798, 16085.91957040216, 16095.436659716912, 14847.1091734496, 15003.774943375858, 15013.849861501381, 15022.924885375585, 15291.923540382282, 816.0615292413742, 818.071488860145, 823.0288259853371, 823.3989187892842, 824.1305422778966, 812.8821320908464, 813.6582635293191, 814.2183503836295, 814.9103598604149, 815.7267315449332, 863.5595846118462, 867.4058270085012, 869.5043825019741, 869.5826003518315, 869.7043005378171, 857.8670306102501, 860.7546849147994, 861.7845538615346, 861.9094995025526, 862.021605477781, 619.9473044791198, 624.5628060357723, 626.6115008694638, 627.8273474794468, 627.993720062798, 14577.562673119823, 14696.26518674062, 14696.853031469655, 14814.629634259121, 14831.406743730238, 12467.93766031164, 13285.13646612967, 13584.252866092362, 13595.388207530685, 13673.589792306248, 12659.987201023896, 12825.486980520767, 13110.672233194151, 13120.803187952188, 13248.47006119754, 4527.411436513232, 4539.4779600346055, 4550.567696068868, 4550.7724613769515, 4555.134524440346, 62246.68876655633, 63860.3613963859, 64204.3579564203, 72355.0, 72387.63806180935, 11568.826467602992, 11655.76688466243, 11681.591144310078, 11831.349275789742, 12086.87913120875, 21401.250956216456, 21417.143314267567, 21421.250256240957, 21428.03573839181, 21455.570888582453, 8356.0, 8370.790730231793, 8842.46945183291, 8931.598078086501, 9145.0397708241, 4588.2888152336445, 4626.953730462652, 4818.831340903122, 4932.8520144395725, 5002.974390250063, 3615.6926661233565, 3679.632036796299, 3718.8326525306425, 3719.0, 3722.8696995605023, 586.0944840221867, 588.7233000489763, 595.1816252652603, 598.7814447726548, 613.8802933428028, 604.353341924137, 606.8816580766708, 636.38270877249, 675.6351570152161, 696.1680791454265, 8900.465972041698, 9110.362274640844, 9131.497767622845, 9153.267738580898, 9498.382605130644, 510.4895104895105, 633.0599060395309, 640.5580149696694, 680.1633990192091, 686.543448606681, 4247.8088486018205, 4470.351798989193, 4504.137972095954, 4551.954480455153, 4683.344331793552, 66728.0, 67333.66333168303, 67438.66280668565, 67523.66238168778, 75533.24466755372, 79311.0, 89818.55090724505, 94024.52987735017, 94501.0, 96854.51572742232, 84317.0, 84646.57676711577, 85232.0, 85914.0, 86002.56998714966, 8962.955185224033, 9116.179543841086, 9220.631174752934, 9376.67181648639, 9467.621295148117, 51262.48737512577, 51426.228606570185, 51583.226251607004, 52657.210141847136, 52734.20898686446, 67745.6612716953, 68108.59457027228, 73765.0, 74399.25600743924, 76076.61961690156, 12012.159148859497, 12620.873791261969, 12798.744025119258, 13028.54400095992, 13583.592492225249, 14324.57026289213, 14717.852821471648, 15364.46224382186, 15520.14639194855, 15698.529044128694, 12878.098533102593, 13229.140105893086, 13261.138026028277, 13265.469381224642, 13303.26832024248, 13773.655658608615, 13902.16587004783, 14018.789718154032, 14223.857761422254, 14905.25473726331, 12153.513859445524, 12539.561115360919, 12632.6841828955, 12775.0, 12805.231686098863, 10620.734481638021, 10801.351918884891, 10959.452027398758, 10968.122550196125, 11239.494222760159, 10633.680989570325, 10867.446150154548, 11176.329420234812, 11216.495257713586, 11217.71955701114], "value": 7371.714228924126, "unit": "score"}], "name": "dromaeo_css", "value": 7165.398143415596, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 111.0, 110.0, 109.0, 109.0, 110.0, 108.0, 109.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 133.0, 178.0, 127.0, 126.0, 154.0, 128.0, 175.0, 133.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 159.0, 161.0, 161.0, 160.0, 160.0, 162.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 127.0, 75.0, 76.0, 74.0, 74.0, 104.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 125.0, 84.0, 84.0, 85.0, 120.0, 84.0, 86.0, 125.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 57.0, 56.0, 57.0, 57.0, 55.0, 56.0, 55.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 62.0, 62.0, 61.0, 61.0, 61.0, 61.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 79.0, 79.0, 82.0, 86.0, 84.0, 82.0, 116.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 119.0, 119.0, 121.0, 128.0, 127.0, 123.0, 140.0, 125.0], "value": 124.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 164.0, 163.0, 162.0, 164.0, 162.0, 167.0, 159.0, 161.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 63.0, 62.0, 62.0, 68.0, 64.0, 63.0, 63.0, 67.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1502.6999999999998, "alertThreshold": 2.0}]} 06:06:21 INFO - Return code: 0 06:06:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:06:21 ERROR - # TBPL SUCCESS # 06:06:21 INFO - Running post-action listener: _resource_record_post_action 06:06:21 INFO - ##### 06:06:21 INFO - ##### Finished run-tests step (success) 06:06:21 INFO - ##### 06:06:21 INFO - Running post-run listener: _resource_record_post_run 06:06:21 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 556253184; Read time: 12; Write time: 503576 06:06:21 INFO - TinderboxPrint: CPU usage
13.2% 06:06:21 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 06:06:21 INFO - TinderboxPrint: I/O write bytes / time
556,253,184 / 503,576 06:06:21 INFO - TinderboxPrint: CPU idle
8,239.7 (86.7%) 06:06:21 INFO - TinderboxPrint: CPU user
1,214.7 (12.8%) 06:06:21 INFO - TinderboxPrint: Swap in / out
0 / 0 06:06:21 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 257175552; Read time: 0; Write time: 285968 06:06:21 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292827136; Read time: 12; Write time: 216572 06:06:22 INFO - Running post-run listener: _upload_blobber_files 06:06:22 INFO - Blob upload gear active. 06:06:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:06:22 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 06:06:22 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:06:22 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:06:22 INFO - (blobuploader) - INFO - Open directory for files ... 06:06:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:06:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:06:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:23 INFO - (blobuploader) - INFO - Done attempting. 06:06:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:06:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:06:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:24 INFO - (blobuploader) - INFO - Done attempting. 06:06:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:06:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:06:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:28 INFO - (blobuploader) - INFO - Done attempting. 06:06:28 INFO - (blobuploader) - INFO - Iteration through files over. 06:06:28 INFO - Return code: 0 06:06:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:06:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:06:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe0ac6aa4410b6c891ef13ba568d54b5be52fd1006d34e5ab6a91c2cce3798931806ad25adc1418b85547db197f48af052fb956e760a1c1d982f7a835745195", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed208b9cab89e0310fc473a194a5373ae7a84ee571f53856247459c61c4960264fb1da4cd600f1dcbfc75d95b9ffde22ba10944a459fee39350dd68b27b154a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575af8c07f252165d8950f264b7eecaf446dd7d2588b12d1183b21705d6f92e0f58b2d385f97a35658c9297b9bd9ed9823561d49e20e4df8a13531f722372a53"} 06:06:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:06:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:06:28 INFO - Contents: 06:06:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe0ac6aa4410b6c891ef13ba568d54b5be52fd1006d34e5ab6a91c2cce3798931806ad25adc1418b85547db197f48af052fb956e760a1c1d982f7a835745195", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed208b9cab89e0310fc473a194a5373ae7a84ee571f53856247459c61c4960264fb1da4cd600f1dcbfc75d95b9ffde22ba10944a459fee39350dd68b27b154a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575af8c07f252165d8950f264b7eecaf446dd7d2588b12d1183b21705d6f92e0f58b2d385f97a35658c9297b9bd9ed9823561d49e20e4df8a13531f722372a53"} 06:06:28 INFO - Running post-run listener: copy_logs_to_upload_dir 06:06:28 INFO - Copying logs to upload dir... 06:06:28 INFO - mkdir: /builds/slave/test/build/upload/logs 06:06:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.571481 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-02 06:06:28.385790) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 06:06:28.389028) ========= 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=0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe0ac6aa4410b6c891ef13ba568d54b5be52fd1006d34e5ab6a91c2cce3798931806ad25adc1418b85547db197f48af052fb956e760a1c1d982f7a835745195", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed208b9cab89e0310fc473a194a5373ae7a84ee571f53856247459c61c4960264fb1da4cd600f1dcbfc75d95b9ffde22ba10944a459fee39350dd68b27b154a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575af8c07f252165d8950f264b7eecaf446dd7d2588b12d1183b21705d6f92e0f58b2d385f97a35658c9297b9bd9ed9823561d49e20e4df8a13531f722372a53"} build_url:https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011482 build_url: 'https://queue.taskcluster.net/v1/task/Le6rKNWxTWGJ8eU02VyMmA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7fe0ac6aa4410b6c891ef13ba568d54b5be52fd1006d34e5ab6a91c2cce3798931806ad25adc1418b85547db197f48af052fb956e760a1c1d982f7a835745195", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed208b9cab89e0310fc473a194a5373ae7a84ee571f53856247459c61c4960264fb1da4cd600f1dcbfc75d95b9ffde22ba10944a459fee39350dd68b27b154a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/575af8c07f252165d8950f264b7eecaf446dd7d2588b12d1183b21705d6f92e0f58b2d385f97a35658c9297b9bd9ed9823561d49e20e4df8a13531f722372a53"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 06:06:28.414658) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:06:28.415034) ========= 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=0333a256a5e4f052382dd180000001b1-1464871553.810766-2054730059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:06:28.501647) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 06:06:28.501973) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 06:06:28.502289) ========= ========= Total master_lag: 0.18 =========