builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-005 starttime: 1464929972.85 results: success (0) buildid: 20160602200611 builduid: b7e4ae978d8f46b6be0fe6e372628047 revision: 0c48702e8ff4cf427e443ed91630465c39f6c4ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:32.846498) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:32.846921) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:32.847227) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:32.881312) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:32.881639) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-02 21:59:33-- 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% 42.7M=0s 2016-06-02 21:59:33 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151806 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.047369) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.047666) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016941 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.105968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.106315) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0c48702e8ff4cf427e443ed91630465c39f6c4ee --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0c48702e8ff4cf427e443ed91630465c39f6c4ee --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=7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-02 21:59:33,620 truncating revision to first 12 chars 2016-06-02 21:59:33,620 Setting DEBUG logging. 2016-06-02 21:59:33,620 attempt 1/10 2016-06-02 21:59:33,620 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0c48702e8ff4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 21:59:33,926 unpacking tar archive at: fx-team-0c48702e8ff4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.617777 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.737367) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.737682) ========= script_repo_revision: 0c48702e8ff4cf427e443ed91630465c39f6c4ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.738080) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.738366) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 21:59:33.746863) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-06-02 21:59:33.747191) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:59:34 INFO - MultiFileLogger online at 20160602 21:59:34 in /builds/slave/test 21:59:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 21:59:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:59:34 INFO - {'append_to_log': False, 21:59:34 INFO - 'base_work_dir': '/builds/slave/test', 21:59:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:59:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:59:34 INFO - 'branch': 'Fx-Team-Non-PGO', 21:59:34 INFO - 'buildbot_json_path': 'buildprops.json', 21:59:34 INFO - 'config_files': ('talos/linux_config.py',), 21:59:34 INFO - 'default_actions': ('clobber', 21:59:34 INFO - 'read-buildbot-config', 21:59:34 INFO - 'download-and-extract', 21:59:34 INFO - 'populate-webroot', 21:59:34 INFO - 'create-virtualenv', 21:59:34 INFO - 'install', 21:59:34 INFO - 'run-tests'), 21:59:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:59:34 INFO - 'download_minidump_stackwalk': True, 21:59:34 INFO - 'download_symbols': 'ondemand', 21:59:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:59:34 INFO - 'tooltool.py': '/tools/tooltool.py', 21:59:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:59:34 INFO - '/tools/misc-python/virtualenv.py')}, 21:59:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:59:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:59:34 INFO - 'installer_path': 'installer.exe', 21:59:34 INFO - 'log_level': 'info', 21:59:34 INFO - 'log_name': 'talos', 21:59:34 INFO - 'log_to_console': True, 21:59:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:59:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:59:34 INFO - 'opt_config_files': (), 21:59:34 INFO - 'pip_index': False, 21:59:34 INFO - 'sps_profile': False, 21:59:34 INFO - 'sps_profile_interval': 0, 21:59:34 INFO - 'suite': 'dromaeojs', 21:59:34 INFO - 'system_bits': '32', 21:59:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:59:34 INFO - 'title': 'talos-linux64-ix-005', 21:59:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:59:34 INFO - 'use_talos_json': True, 21:59:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:59:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:59:34 INFO - 'work_dir': 'build'} 21:59:34 INFO - ##### 21:59:34 INFO - ##### Running clobber step. 21:59:34 INFO - ##### 21:59:34 INFO - Running pre-action listener: _resource_record_pre_action 21:59:34 INFO - Running main action method: clobber 21:59:34 INFO - rmtree: /builds/slave/test/build 21:59:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:59:34 INFO - Running post-action listener: _resource_record_post_action 21:59:34 INFO - ##### 21:59:34 INFO - ##### Finished clobber step (success) 21:59:34 INFO - ##### 21:59:34 INFO - ##### 21:59:34 INFO - ##### Running read-buildbot-config step. 21:59:34 INFO - ##### 21:59:34 INFO - Running pre-action listener: _resource_record_pre_action 21:59:34 INFO - Running main action method: read_buildbot_config 21:59:34 INFO - Using buildbot properties: 21:59:34 INFO - { 21:59:34 INFO - "project": "", 21:59:34 INFO - "product": "firefox", 21:59:34 INFO - "script_repo_revision": "production", 21:59:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:59:34 INFO - "repository": "", 21:59:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:59:34 INFO - "buildid": "20160602200611", 21:59:34 INFO - "pgo_build": "False", 21:59:34 INFO - "basedir": "/builds/slave/test", 21:59:34 INFO - "buildnumber": 1745, 21:59:34 INFO - "slavename": "talos-linux64-ix-005", 21:59:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:59:34 INFO - "platform": "ubuntu64_hw", 21:59:34 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:59:34 INFO - "branch": "fx-team", 21:59:34 INFO - "repo_path": "integration/fx-team", 21:59:34 INFO - "revision": "0c48702e8ff4cf427e443ed91630465c39f6c4ee", 21:59:34 INFO - "stage_platform": "linux64", 21:59:34 INFO - "builduid": "b7e4ae978d8f46b6be0fe6e372628047", 21:59:34 INFO - "slavebuilddir": "test" 21:59:34 INFO - } 21:59:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:59:34 INFO - Running post-action listener: _resource_record_post_action 21:59:34 INFO - ##### 21:59:34 INFO - ##### Finished read-buildbot-config step (success) 21:59:34 INFO - ##### 21:59:34 INFO - ##### 21:59:34 INFO - ##### Running download-and-extract step. 21:59:34 INFO - ##### 21:59:34 INFO - Running pre-action listener: _resource_record_pre_action 21:59:34 INFO - Running main action method: download_and_extract 21:59:34 INFO - mkdir: /builds/slave/test/build/tests 21:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:34 INFO - https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:59:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:59:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:59:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:39 INFO - Downloaded 1361 bytes. 21:59:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:59:39 INFO - Using the following test package requirements: 21:59:39 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:59:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:59:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'jsshell-linux-x86_64.zip'], 21:59:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:59:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:59:39 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:59:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:59:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:59:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:39 INFO - https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:59:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:59:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:40 INFO - Downloaded 22866938 bytes. 21:59:40 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'] 21:59:40 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 21:59:41 INFO - Return code: 0 21:59:41 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 21:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:41 INFO - https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:59:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:59:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:50 INFO - Downloaded 22866938 bytes. 21:59:50 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'] 21:59:50 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 21:59:51 INFO - Return code: 0 21:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:51 INFO - https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:59:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:52 INFO - Downloaded 10834055 bytes. 21:59:52 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'] 21:59:52 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 21:59:52 INFO - Return code: 0 21:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:52 INFO - https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:59:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1-aLizpRjedHF4l9pHxoA/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 21:59:58 INFO - Downloaded 55083866 bytes. 21:59:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:58 INFO - mkdir: /builds/slave/test/properties 21:59:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:59:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:59:58 INFO - Contents: 21:59:58 INFO - build_url:https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:59:58 INFO - Running post-action listener: _resource_record_post_action 21:59:58 INFO - Running post-action listener: set_extra_try_arguments 21:59:58 INFO - ##### 21:59:58 INFO - ##### Finished download-and-extract step (success) 21:59:58 INFO - ##### 21:59:58 INFO - ##### 21:59:58 INFO - ##### Running populate-webroot step. 21:59:58 INFO - ##### 21:59:58 INFO - Running pre-action listener: _resource_record_pre_action 21:59:58 INFO - Running main action method: populate_webroot 21:59:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:59:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:59:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:59:58 INFO - '--tppagecycles', 21:59:58 INFO - '7'], 21:59:58 INFO - 'tests': ['tsvgm']}}, 21:59:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:59:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:59:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:59:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:59:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['damp', 'tps']}, 21:59:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'tests': ['damp', 'tps']}, 21:59:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['dromaeo_dom']}, 21:59:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:59:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other-e10s': {'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tsvgx', 21:59:58 INFO - 'tsvgr_opacity', 21:59:58 INFO - 'tart', 21:59:58 INFO - 'tscrollx', 21:59:58 INFO - 'cart']}, 21:59:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:59:58 INFO - 'tsvgr_opacity', 21:59:58 INFO - 'tart', 21:59:58 INFO - 'tscrollx', 21:59:58 INFO - 'cart']}, 21:59:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tp5o']}, 21:59:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'tests': ['tp5o']}, 21:59:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s', 21:59:58 INFO - '--xperf_path', 21:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:58 INFO - 'tests': ['tp5n']}, 21:59:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--xperf_path', 21:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:58 INFO - 'tests': ['tp5n']}}, 21:59:58 INFO - 'talos.zip': {'path': '', 21:59:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:59:58 INFO - Running post-action listener: _resource_record_post_action 21:59:58 INFO - ##### 21:59:58 INFO - ##### Finished populate-webroot step (success) 21:59:58 INFO - ##### 21:59:58 INFO - ##### 21:59:58 INFO - ##### Running create-virtualenv step. 21:59:58 INFO - ##### 21:59:58 INFO - Running pre-action listener: _resource_record_pre_action 21:59:58 INFO - Running main action method: create_virtualenv 21:59:58 INFO - Creating virtualenv /builds/slave/test/build/venv 21:59:58 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 21:59:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:59:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:59:58 INFO - Using real prefix '/usr' 21:59:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:59:59 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:01 INFO - Installing pip.................done. 22:00:01 INFO - Return code: 0 22:00:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:01 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:00:01 INFO - Using env: {'DISPLAY': ':0', 22:00:01 INFO - 'HOME': '/home/cltbld', 22:00:01 INFO - 'LANG': 'en_US.UTF-8', 22:00:01 INFO - 'LANGUAGE': 'en_US:en', 22:00:01 INFO - 'LOGNAME': 'cltbld', 22:00:01 INFO - 'MAIL': '/var/mail/cltbld', 22:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:01 INFO - 'MOZ_NO_REMOTE': '1', 22:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:01 INFO - 'NO_EM_RESTART': '1', 22:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:01 INFO - 'PWD': '/builds/slave/test', 22:00:01 INFO - 'SHELL': '/bin/bash', 22:00:01 INFO - 'SHLVL': '1', 22:00:01 INFO - 'TERM': 'linux', 22:00:01 INFO - 'TMOUT': '86400', 22:00:01 INFO - 'USER': 'cltbld', 22:00:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:01 INFO - '_': '/tools/buildbot/bin/python'} 22:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:00:01 INFO - Cleaning up... 22:00:01 INFO - Return code: 0 22:00:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:01 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:01 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 22:00:01 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 22:00:01 INFO - Using env: {'DISPLAY': ':0', 22:00:01 INFO - 'HOME': '/home/cltbld', 22:00:01 INFO - 'LANG': 'en_US.UTF-8', 22:00:01 INFO - 'LANGUAGE': 'en_US:en', 22:00:01 INFO - 'LOGNAME': 'cltbld', 22:00:01 INFO - 'MAIL': '/var/mail/cltbld', 22:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:01 INFO - 'MOZ_NO_REMOTE': '1', 22:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:01 INFO - 'NO_EM_RESTART': '1', 22:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:01 INFO - 'PWD': '/builds/slave/test', 22:00:01 INFO - 'SHELL': '/bin/bash', 22:00:01 INFO - 'SHLVL': '1', 22:00:01 INFO - 'TERM': 'linux', 22:00:01 INFO - 'TMOUT': '86400', 22:00:01 INFO - 'USER': 'cltbld', 22:00:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:01 INFO - '_': '/tools/buildbot/bin/python'} 22:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:01 INFO - Downloading/unpacking psutil>=3.1.1 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:03 INFO - Installing collected packages: psutil 22:00:03 INFO - Running setup.py install for psutil 22:00:03 INFO - building 'psutil._psutil_linux' extension 22:00:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:00:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:00:04 INFO - building 'psutil._psutil_posix' extension 22:00:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:00:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:04 INFO - Successfully installed psutil 22:00:04 INFO - Cleaning up... 22:00:04 INFO - Return code: 0 22:00:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:04 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:04 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 22:00:04 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 22:00:04 INFO - Using env: {'DISPLAY': ':0', 22:00:04 INFO - 'HOME': '/home/cltbld', 22:00:04 INFO - 'LANG': 'en_US.UTF-8', 22:00:04 INFO - 'LANGUAGE': 'en_US:en', 22:00:04 INFO - 'LOGNAME': 'cltbld', 22:00:04 INFO - 'MAIL': '/var/mail/cltbld', 22:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:04 INFO - 'MOZ_NO_REMOTE': '1', 22:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:04 INFO - 'NO_EM_RESTART': '1', 22:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:04 INFO - 'PWD': '/builds/slave/test', 22:00:04 INFO - 'SHELL': '/bin/bash', 22:00:04 INFO - 'SHLVL': '1', 22:00:04 INFO - 'TERM': 'linux', 22:00:04 INFO - 'TMOUT': '86400', 22:00:04 INFO - 'USER': 'cltbld', 22:00:04 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:04 INFO - '_': '/tools/buildbot/bin/python'} 22:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:00:06 INFO - Installing collected packages: mozsystemmonitor 22:00:06 INFO - Running setup.py install for mozsystemmonitor 22:00:06 INFO - Successfully installed mozsystemmonitor 22:00:06 INFO - Cleaning up... 22:00:06 INFO - Return code: 0 22:00:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:06 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:00:06 INFO - Using env: {'DISPLAY': ':0', 22:00:06 INFO - 'HOME': '/home/cltbld', 22:00:06 INFO - 'LANG': 'en_US.UTF-8', 22:00:06 INFO - 'LANGUAGE': 'en_US:en', 22:00:06 INFO - 'LOGNAME': 'cltbld', 22:00:06 INFO - 'MAIL': '/var/mail/cltbld', 22:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:06 INFO - 'MOZ_NO_REMOTE': '1', 22:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:06 INFO - 'NO_EM_RESTART': '1', 22:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:06 INFO - 'PWD': '/builds/slave/test', 22:00:06 INFO - 'SHELL': '/bin/bash', 22:00:06 INFO - 'SHLVL': '1', 22:00:06 INFO - 'TERM': 'linux', 22:00:06 INFO - 'TMOUT': '86400', 22:00:06 INFO - 'USER': 'cltbld', 22:00:06 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:06 INFO - '_': '/tools/buildbot/bin/python'} 22:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:06 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:08 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:00:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:00:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:09 INFO - Downloading docopt-0.6.1.tar.gz 22:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:00:09 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:09 INFO - Running setup.py install for blobuploader 22:00:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:00:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:00:09 INFO - Running setup.py install for requests 22:00:10 INFO - Running setup.py install for docopt 22:00:10 INFO - Successfully installed blobuploader requests docopt 22:00:10 INFO - Cleaning up... 22:00:10 INFO - Return code: 0 22:00:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:10 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:00:10 INFO - Using env: {'DISPLAY': ':0', 22:00:10 INFO - 'HOME': '/home/cltbld', 22:00:10 INFO - 'LANG': 'en_US.UTF-8', 22:00:10 INFO - 'LANGUAGE': 'en_US:en', 22:00:10 INFO - 'LOGNAME': 'cltbld', 22:00:10 INFO - 'MAIL': '/var/mail/cltbld', 22:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:10 INFO - 'MOZ_NO_REMOTE': '1', 22:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:10 INFO - 'NO_EM_RESTART': '1', 22:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:10 INFO - 'PWD': '/builds/slave/test', 22:00:10 INFO - 'SHELL': '/bin/bash', 22:00:10 INFO - 'SHLVL': '1', 22:00:10 INFO - 'TERM': 'linux', 22:00:10 INFO - 'TMOUT': '86400', 22:00:10 INFO - 'USER': 'cltbld', 22:00:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:10 INFO - '_': '/tools/buildbot/bin/python'} 22:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:10 INFO - Running setup.py (path:/tmp/pip-XaxkP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:10 INFO - Running setup.py (path:/tmp/pip-AknNMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:10 INFO - Running setup.py (path:/tmp/pip-rC2HwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:10 INFO - Running setup.py (path:/tmp/pip-tJ5ic2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:10 INFO - Running setup.py (path:/tmp/pip-WsfVTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:10 INFO - Running setup.py (path:/tmp/pip-Of2pDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:10 INFO - Running setup.py (path:/tmp/pip-42yWzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:10 INFO - Running setup.py (path:/tmp/pip-ajXfiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:10 INFO - Running setup.py (path:/tmp/pip-H8hH1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:11 INFO - Running setup.py (path:/tmp/pip-e2Nuym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:11 INFO - Running setup.py (path:/tmp/pip-IYzHjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:11 INFO - Running setup.py (path:/tmp/pip-NExQ_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:11 INFO - Running setup.py (path:/tmp/pip-xU7qi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:11 INFO - Running setup.py (path:/tmp/pip-hvYC1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:11 INFO - Running setup.py (path:/tmp/pip-4N9or4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:11 INFO - Running setup.py (path:/tmp/pip-oP6rza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:11 INFO - Running setup.py (path:/tmp/pip-X14Na5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:11 INFO - Running setup.py install for manifestparser 22:00:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:00:11 INFO - Running setup.py install for mozcrash 22:00:11 INFO - Running setup.py install for mozdebug 22:00:11 INFO - Running setup.py install for mozdevice 22:00:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozfile 22:00:12 INFO - Running setup.py install for mozhttpd 22:00:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozinfo 22:00:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozInstall 22:00:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozleak 22:00:12 INFO - Running setup.py install for mozlog 22:00:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for moznetwork 22:00:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozprocess 22:00:12 INFO - Running setup.py install for mozprofile 22:00:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozrunner 22:00:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for mozscreenshot 22:00:13 INFO - Running setup.py install for moztest 22:00:13 INFO - Running setup.py install for mozversion 22:00:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:00:13 INFO - Cleaning up... 22:00:13 INFO - Return code: 0 22:00:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:13 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:00:13 INFO - Using env: {'DISPLAY': ':0', 22:00:13 INFO - 'HOME': '/home/cltbld', 22:00:13 INFO - 'LANG': 'en_US.UTF-8', 22:00:13 INFO - 'LANGUAGE': 'en_US:en', 22:00:13 INFO - 'LOGNAME': 'cltbld', 22:00:13 INFO - 'MAIL': '/var/mail/cltbld', 22:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:13 INFO - 'MOZ_NO_REMOTE': '1', 22:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:13 INFO - 'NO_EM_RESTART': '1', 22:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:13 INFO - 'PWD': '/builds/slave/test', 22:00:13 INFO - 'SHELL': '/bin/bash', 22:00:13 INFO - 'SHLVL': '1', 22:00:13 INFO - 'TERM': 'linux', 22:00:13 INFO - 'TMOUT': '86400', 22:00:13 INFO - 'USER': 'cltbld', 22:00:13 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:13 INFO - '_': '/tools/buildbot/bin/python'} 22:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:13 INFO - Running setup.py (path:/tmp/pip-UY_kZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:13 INFO - Running setup.py (path:/tmp/pip-Bor9aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:13 INFO - Running setup.py (path:/tmp/pip-3iwwej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:13 INFO - Running setup.py (path:/tmp/pip-xcDVaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:13 INFO - Running setup.py (path:/tmp/pip-jtmhAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:14 INFO - Running setup.py (path:/tmp/pip-6BDqJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:14 INFO - Running setup.py (path:/tmp/pip-KKNvNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:14 INFO - Running setup.py (path:/tmp/pip-rg0Gou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:14 INFO - Running setup.py (path:/tmp/pip-jzhRDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:14 INFO - Running setup.py (path:/tmp/pip-aQneJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:14 INFO - Running setup.py (path:/tmp/pip-gCkyt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:14 INFO - Running setup.py (path:/tmp/pip-NIaF5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:14 INFO - Running setup.py (path:/tmp/pip-kj08Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:14 INFO - Running setup.py (path:/tmp/pip-K0qRJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:14 INFO - Running setup.py (path:/tmp/pip-EA1S9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:14 INFO - Running setup.py (path:/tmp/pip-T6yOIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:14 INFO - Running setup.py (path:/tmp/pip-gWeJ5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:00:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:16 INFO - Downloading blessings-1.6.tar.gz 22:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:00:16 INFO - Installing collected packages: blessings 22:00:16 INFO - Running setup.py install for blessings 22:00:16 INFO - Successfully installed blessings 22:00:16 INFO - Cleaning up... 22:00:17 INFO - Return code: 0 22:00:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:00:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:17 INFO - Reading from file tmpfile_stdout 22:00:17 INFO - Current package versions: 22:00:17 INFO - argparse == 1.2.1 22:00:17 INFO - blessings == 1.6 22:00:17 INFO - blobuploader == 1.2.4 22:00:17 INFO - docopt == 0.6.1 22:00:17 INFO - manifestparser == 1.1 22:00:17 INFO - mozInstall == 1.12 22:00:17 INFO - mozcrash == 0.17 22:00:17 INFO - mozdebug == 0.1 22:00:17 INFO - mozdevice == 0.48 22:00:17 INFO - mozfile == 1.2 22:00:17 INFO - mozhttpd == 0.7 22:00:17 INFO - mozinfo == 0.9 22:00:17 INFO - mozleak == 0.1 22:00:17 INFO - mozlog == 3.2 22:00:17 INFO - moznetwork == 0.27 22:00:17 INFO - mozprocess == 0.22 22:00:17 INFO - mozprofile == 0.28 22:00:17 INFO - mozrunner == 6.11 22:00:17 INFO - mozscreenshot == 0.1 22:00:17 INFO - mozsystemmonitor == 0.3 22:00:17 INFO - moztest == 0.7 22:00:17 INFO - mozversion == 1.4 22:00:17 INFO - psutil == 3.1.1 22:00:17 INFO - requests == 1.2.3 22:00:17 INFO - wsgiref == 0.1.2 22:00:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:17 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:00:17 INFO - Using env: {'DISPLAY': ':0', 22:00:17 INFO - 'HOME': '/home/cltbld', 22:00:17 INFO - 'LANG': 'en_US.UTF-8', 22:00:17 INFO - 'LANGUAGE': 'en_US:en', 22:00:17 INFO - 'LOGNAME': 'cltbld', 22:00:17 INFO - 'MAIL': '/var/mail/cltbld', 22:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:17 INFO - 'MOZ_NO_REMOTE': '1', 22:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:17 INFO - 'NO_EM_RESTART': '1', 22:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:17 INFO - 'PWD': '/builds/slave/test', 22:00:17 INFO - 'SHELL': '/bin/bash', 22:00:17 INFO - 'SHLVL': '1', 22:00:17 INFO - 'TERM': 'linux', 22:00:17 INFO - 'TMOUT': '86400', 22:00:17 INFO - 'USER': 'cltbld', 22:00:17 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:17 INFO - '_': '/tools/buildbot/bin/python'} 22:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:00:17 INFO - Cleaning up... 22:00:17 INFO - Return code: 0 22:00:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:17 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:00:17 INFO - Using env: {'DISPLAY': ':0', 22:00:17 INFO - 'HOME': '/home/cltbld', 22:00:17 INFO - 'LANG': 'en_US.UTF-8', 22:00:17 INFO - 'LANGUAGE': 'en_US:en', 22:00:17 INFO - 'LOGNAME': 'cltbld', 22:00:17 INFO - 'MAIL': '/var/mail/cltbld', 22:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:17 INFO - 'MOZ_NO_REMOTE': '1', 22:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:17 INFO - 'NO_EM_RESTART': '1', 22:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:17 INFO - 'PWD': '/builds/slave/test', 22:00:17 INFO - 'SHELL': '/bin/bash', 22:00:17 INFO - 'SHLVL': '1', 22:00:17 INFO - 'TERM': 'linux', 22:00:17 INFO - 'TMOUT': '86400', 22:00:17 INFO - 'USER': 'cltbld', 22:00:17 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:17 INFO - '_': '/tools/buildbot/bin/python'} 22:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:17 INFO - Downloading/unpacking jsonschema 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:00:19 INFO - Downloading/unpacking functools32 (from jsonschema) 22:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:19 INFO - Downloading functools32-3.2.3-2.tar.gz 22:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:00:19 INFO - warning: no files found matching '*.txt' 22:00:19 INFO - no previously-included directories found matching 'build' 22:00:19 INFO - no previously-included directories found matching 'dist' 22:00:19 INFO - no previously-included directories found matching '.git*' 22:00:19 INFO - Installing collected packages: jsonschema, functools32 22:00:20 INFO - Running setup.py install for functools32 22:00:20 INFO - warning: no files found matching '*.txt' 22:00:20 INFO - no previously-included directories found matching 'build' 22:00:20 INFO - no previously-included directories found matching 'dist' 22:00:20 INFO - no previously-included directories found matching '.git*' 22:00:20 INFO - Successfully installed jsonschema functools32 22:00:20 INFO - Cleaning up... 22:00:20 INFO - Return code: 0 22:00:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:20 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 0x7fea8b8fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x275b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:00:20 INFO - Using env: {'DISPLAY': ':0', 22:00:20 INFO - 'HOME': '/home/cltbld', 22:00:20 INFO - 'LANG': 'en_US.UTF-8', 22:00:20 INFO - 'LANGUAGE': 'en_US:en', 22:00:20 INFO - 'LOGNAME': 'cltbld', 22:00:20 INFO - 'MAIL': '/var/mail/cltbld', 22:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:20 INFO - 'MOZ_NO_REMOTE': '1', 22:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:20 INFO - 'NO_EM_RESTART': '1', 22:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:20 INFO - 'PWD': '/builds/slave/test', 22:00:20 INFO - 'SHELL': '/bin/bash', 22:00:20 INFO - 'SHLVL': '1', 22:00:20 INFO - 'TERM': 'linux', 22:00:20 INFO - 'TMOUT': '86400', 22:00:20 INFO - 'USER': 'cltbld', 22:00:20 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:20 INFO - '_': '/tools/buildbot/bin/python'} 22:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:20 INFO - Downloading/unpacking flake8 22:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:00:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:00:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:00:22 INFO - no previously-included directories found matching 'docs/_build' 22:00:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:00:23 INFO - Running setup.py install for pep8 22:00:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:00:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:00:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:00:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:00:23 INFO - no previously-included directories found matching 'docs/_build' 22:00:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:00:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:00:23 INFO - Cleaning up... 22:00:23 INFO - Return code: 0 22:00:23 INFO - Running post-action listener: _resource_record_post_action 22:00:23 INFO - Running post-action listener: _start_resource_monitoring 22:00:23 INFO - Starting resource monitoring. 22:00:23 INFO - ##### 22:00:23 INFO - ##### Finished create-virtualenv step (success) 22:00:23 INFO - ##### 22:00:23 INFO - ##### 22:00:23 INFO - ##### Running install step. 22:00:23 INFO - ##### 22:00:23 INFO - Running pre-action listener: _resource_record_pre_action 22:00:23 INFO - Running main action method: install 22:00:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:23 INFO - Reading from file tmpfile_stdout 22:00:23 INFO - Detecting whether we're running mozinstall >=1.0... 22:00:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:00:23 INFO - Reading from file tmpfile_stdout 22:00:23 INFO - Output received: 22:00:23 INFO - Usage: mozinstall [options] installer 22:00:23 INFO - Options: 22:00:23 INFO - -h, --help show this help message and exit 22:00:23 INFO - -d DEST, --destination=DEST 22:00:23 INFO - Directory to install application into. [default: 22:00:23 INFO - "/builds/slave/test"] 22:00:23 INFO - --app=APP Application being installed. [default: firefox] 22:00:23 INFO - mkdir: /builds/slave/test/build/application 22:00:23 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'] 22:00:23 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 22:00:39 INFO - Reading from file tmpfile_stdout 22:00:39 INFO - Output received: 22:00:39 INFO - /builds/slave/test/build/application/firefox/firefox 22:00:39 INFO - Running post-action listener: _resource_record_post_action 22:00:39 INFO - ##### 22:00:39 INFO - ##### Finished install step (success) 22:00:39 INFO - ##### 22:00:39 INFO - ##### 22:00:39 INFO - ##### Running run-tests step. 22:00:39 INFO - ##### 22:00:39 INFO - Running pre-action listener: _resource_record_pre_action 22:00:39 INFO - Running main action method: run_tests 22:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:00:39 INFO - Python 2.7.3 22:00:39 INFO - Return code: 0 22:00:39 INFO - grabbing minidump binary from tooltool 22:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:39 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 0x27ea9c0>, '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 0x27eb8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ebd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:00:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:00:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:00:39 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 22:00:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:00:39 INFO - Return code: 0 22:00:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:00:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:00:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:00:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:00:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:00:40 INFO - Return code: 0 22:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:00:40 INFO - Using env: {'DISPLAY': ':0', 22:00:40 INFO - 'HOME': '/home/cltbld', 22:00:40 INFO - 'LANG': 'en_US.UTF-8', 22:00:40 INFO - 'LANGUAGE': 'en_US:en', 22:00:40 INFO - 'LOGNAME': 'cltbld', 22:00:40 INFO - 'MAIL': '/var/mail/cltbld', 22:00:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:00:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:40 INFO - 'MOZ_NO_REMOTE': '1', 22:00:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:40 INFO - 'NO_EM_RESTART': '1', 22:00:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:40 INFO - 'PWD': '/builds/slave/test', 22:00:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:00:40 INFO - 'SHELL': '/bin/bash', 22:00:40 INFO - 'SHLVL': '1', 22:00:40 INFO - 'TERM': 'linux', 22:00:40 INFO - 'TMOUT': '86400', 22:00:40 INFO - 'USER': 'cltbld', 22:00:40 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012', 22:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:40 INFO - '_': '/tools/buildbot/bin/python'} 22:00:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:00:40 INFO - mozversion application_buildid: 20160602200611 22:00:40 INFO - mozversion application_changeset: 0c48702e8ff4cf427e443ed91630465c39f6c4ee 22:00:40 INFO - mozversion application_display_name: Nightly 22:00:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:00:40 INFO - mozversion application_name: Firefox 22:00:40 INFO - mozversion application_remotingname: firefox 22:00:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:00:40 INFO - mozversion application_vendor: Mozilla 22:00:40 INFO - mozversion application_version: 49.0a1 22:00:40 INFO - mozversion platform_buildid: 20160602200611 22:00:40 INFO - mozversion platform_changeset: 0c48702e8ff4cf427e443ed91630465c39f6c4ee 22:00:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:00:40 INFO - mozversion platform_version: 49.0a1 22:00:40 INFO - using testdate: 1464930040 22:00:40 INFO - actual date: 1464930040 22:00:40 INFO - starting webserver on 'localhost:60362' 22:00:40 INFO - SUITE-START | Running 2 tests 22:00:40 INFO - TEST-START | dromaeo_css 22:00:40 INFO - operating with platform_type : linux_ 22:00:40 INFO - Initialising browser for dromaeo_css test... 22:00:40 INFO - TEST-INFO | started process 24921 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Lg7xU/profile http://localhost:60362/getInfo.html) 22:00:44 INFO - PROCESS | 24921 | __metrics Screen width/height:1600/1200 22:00:44 INFO - PROCESS | 24921 | colorDepth:24 22:00:44 INFO - PROCESS | 24921 | Browser inner width/height: 1024/697 22:00:44 INFO - PROCESS | 24921 | __metrics 22:00:45 ERROR - PROCESS | 24921 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:00:46 INFO - TEST-INFO | 24921: exit 0 22:00:46 INFO - Browser initialized. 22:00:46 INFO - Running cycle 1/1 for dromaeo_css test... 22:00:46 INFO - TEST-INFO | started process 25001 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Lg7xU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:58 INFO - PROCESS | 25001 | RSS: Main: 157655040 22:00:58 INFO - PROCESS | 25001 | 22:01:49 INFO - PROCESS | 25001 | 1464930109937 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:04:01 INFO - PROCESS | 25001 | Cycle 1(1): loaded http://localhost:60362/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60362/tests/dromaeo/cssquery-ext.html) 22:04:02 INFO - PROCESS | 25001 | RSS: Main: 263876608 22:04:02 INFO - PROCESS | 25001 | 22:07:05 INFO - PROCESS | 25001 | Cycle 1(1): loaded http://localhost:60362/tests/dromaeo/cssquery-ext.html (next: http://localhost:60362/tests/dromaeo/cssquery-jquery.html) 22:07:06 INFO - PROCESS | 25001 | RSS: Main: 231673856 22:07:06 INFO - PROCESS | 25001 | 22:10:08 INFO - PROCESS | 25001 | Cycle 1(1): loaded http://localhost:60362/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60362/tests/dromaeo/cssquery-mootools.html) 22:10:09 INFO - PROCESS | 25001 | RSS: Main: 239386624 22:10:09 INFO - PROCESS | 25001 | 22:13:11 INFO - PROCESS | 25001 | Cycle 1(1): loaded http://localhost:60362/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60362/tests/dromaeo/cssquery-prototype.html) 22:13:12 INFO - PROCESS | 25001 | RSS: Main: 235552768 22:13:12 INFO - PROCESS | 25001 | 22:16:15 INFO - PROCESS | 25001 | Cycle 1(1): loaded http://localhost:60362/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60362/tests/dromaeo/cssquery-yui.html) 22:16:15 INFO - PROCESS | 25001 | RSS: Main: 218095616 22:16:15 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | Cycle 1(1): loaded http://localhost:60362/tests/dromaeo/cssquery-yui.html (next: http://localhost:60362/tests/dromaeo/cssquery-dojo.html) 22:19:18 INFO - PROCESS | 25001 | RSS: Main: 263094272 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | __start_tp_report 22:19:18 INFO - PROCESS | 25001 | _x_x_mozilla_page_load 22:19:18 INFO - PROCESS | 25001 | _x_x_mozilla_page_load_details 22:19:18 INFO - PROCESS | 25001 | |i|pagename|runs| 22:19:18 INFO - PROCESS | 25001 | |0;dojo.html;2685.6911455182653;2703.6620422447186;2709.674839019318;2739.6027576001493;2754.7382998615126;4688.671792974493;4768.356271903292;4796.064767370364;4821.156297647911;4859.00390419964;4774.40319960005;4797.592204662599;4841;4860.368152140217;4862.586680132193;4427.933580996271;4456.108778244348;4477.708948918318;4505;4507.954920450787;4457.26455134904;4516.661250406232;4551.908961820762;4587.954120458803;4603.930941035887;4461.286194208911;4501.662375321847;4504.031633198845;4513.571210734991;4549.044700612875;4319.24413227687;4344.956550434486;4366.257736184856;4386.385905973167;4425.557444255581;4291.6137547620865;4306.870793876157;4363.585459381386;4384.824607015716;4417.933730994037;4684.227102528079;4689.671722979393;4710.24636058229;4739.4075740532435;4743.09881122589;4943.456219815817;5004.674696144739;5061.519155680241;5067.417247016567;5143.5113664201845;5173.534381905609;5176.818811341585;5177.922331165036;5183.792648294064;5201.531862132389;4708.246680531167;4809.567138957441;4827.637927155442;4852.247901575226;4857.9028419432125;5222.92165617516;5274;5339.759710813023;5341.813036543703;5351.759170837322;5049.343585333883;5050.772715227824;5106.310648062538;5112;5128.051310507568;4938.43208031081;5038.2190760432195;5051.06555287273;5078.771455284521;5108.821191258363;5032.572231360376;5048.066107770074;5068.797248110108;5092.592592592584;5128.1282182029145;5057.848264552069;5060.49395060491;5066.986602679479;5069.822556210515;5126.871828204325;4255.76593287366;4304.526502084768;4309.375140604658;4338.8047537860875;4395.099004704028;3801.6388443098413;3804.1630841214887;3840.807959602009;3888.8833334999995;3918.5101862267215;4499.460064792246;4508.211063063951;4518.299663552155;4551.271796512542;4554;5217.34783152106;5221.634485586049;5229.712365819841;5234.2933703949475;5283.762230699628;4283.850065247763;4450.844220452269;4484.2376795944765;4492.9775351123035;4510.50384457716;4679.227927391942;4690.624750019991;4693.671442998957;4697.201475749131;4742.383490146259;4699.08367868276;4825.010872771003;4856.1987272099705;4875.366202393666;4915.705057696549;5075.923861142012;5159.045576568347;5183.948160518346;5209.71346575942;5213.348331458567;4802.039592081667;4844.079624871263;4849.830255941025;4852.417709874838;4902.338184345211;4920.1389756791705;5045.62157838171;5124.052050370693;5132.5893928486375;5141.767575464864;5147.819826306061;5192.532672059533;5216.243644671577;5218.425973142876;5268.78924843002;5051.368578927634;5052.065367906949;5056.696598204119;5062.265971434048;5114.360704911886;4856.732879691651;4979.452260251442;4999;5042.84871453857;5062.82280120194;5014.373203349582;5036.143855544639;5054.368203974504;5063.0380227756605;5074.2642316864585;5116.641835071509;5117.667351622133;5130.538251557377;5170.844874653766;5176.792928282826;4777.187878060808;4800.4479484859685;4825.469198388104;4863.95136048635;5008.899822003613 22:19:18 INFO - PROCESS | 25001 | |1;ext.html;560.1569637695268;586.9823905282842;602.6113157013724;603.572550917081;604.4046614085127;28614.570781438295;41467.79266103673;42384.36423453651;42562;42632.36051459227;40641;41239.79380103095;41296;41324.380134297935;41353.58646413535;7524.924750752504;7647.655855486502;7721.806954826118;7730.9226907731045;7811;7484.550926944374;9936.90063099367;10231.846522302172;10248.897511024868;10497;10225.693229203098;10302.433366164858;10309.690709278733;10314.9484252579;10316.071553560178;7466.104067511879;7675;10018.899811001906;10086;10091.444970526618;7222.674979625929;9824.361416507903;10247.128994035442;10256.282060255786;10275.89724102757;36220.45669314936;36734.63265367339;36810.63189368099;39583.20833583341;39597.802010990046;3107.37852429515;3114.3304189599116;3201.983990080035;3219.4526930421885;3239.838008099609;30250;30448;30654.54018189729;30840.228994275098;31240.062798116094;3534.91162720934;3570.107473131717;3571.964280357189;3611.093134741468;3637.4543818427446;270855;272519;273179;273363;273818.6309068462;59826;60178.39821601728;60664.39335606676;60753;60811.39188608146;63251;63438.19370837764;63444;63568;63621.36378636247;14915.328810203568;15336.539903802905;15349.769753453707;15351.155686437352;15354.309056092503;10514.21143414247;11384.829227561593;11499.827502587468;11574.47914843834;11681.06551475897;19190.217418474524;19231.230750769813;19244.61510769777;19351;19391;6974.895376569356;7063.2583578724525;7216.242294558996;7222.494425390278;7290.781276561712;819.5779173725537;947.3652652722692;948.5304774136846;951.871497347863;1003.5684655598163;27162.5925611116;28144.71855281462;28485.287867803057;28960.27599309992;28977.855110724733;947.7207316404059;1008.5411137932223;1055.4458909072798;1083.8320060390652;1137.1528211482332;14692.559223223321;15107.848921510644;15254.313555890012;15357.045617682597;15372.538823835303;359555;359994;360255;363324;363861;50709;51411.228831567554;51482.48517514852;51503.484965150616;51559;1072.8176210044312;1177.1171621284036;1215.295128825279;1224.3082658298033;1265.190278221957;28632.284192895346;29188.124356269345;29384.85307573534;29649.110526684235;29742.471889888737;43152.784236079875;43227.245003050266;43688.126237475706;43812.34281485716;43911.56088439203;307403;309029;309217;309239;310209;30974.915877944164;34027.829860850536;38760.22479550337;39052.6094739049;42528.787356063025;43079.35380969225;43301.783491082344;43804.34293485536;43846.34230486481;44168.55831441644;36155.81922090373;36839.44740828836;37402.625973739916;37421.43867842039;38035.239295214495;36992.445113322785;37136.914939357186;37166.25667486689;37562;37889;35927.46108808422;36261.09347266234;36347.81826090853;37020;37154.07114822151;31259.218519536284;33042;33216.80752385988;33432.33135337328;33592.32815343728;25122.62316065297;25242.74757252404;25450.618240726035;25750.484990299716;26046.479070418864 22:19:18 INFO - PROCESS | 25001 | |2;jquery.html;363.13935971746963;363.62183329337483;364.0679859559526;373.89904725724074;381.30031392394955;15766.76349854753;17110.486685399443;17158.48524544266;17544;17554.736678949826;16782.244798984044;16866.578335541617;17176.742348864776;17511;17583.560410989732;5034.320366750488;5199.558037566811;5209.635325527214;5219.086659834533;5219.269302297663;3748.6626203641667;3909.2963266612;5106.821261255856;5672.68800215988;6032.336442991267;6040.75836966523;6044.304904935944;6046.758129674808;6090.873188460127;6100.267967843865;5841.532677385799;5951.047832346825;6004.6997650117755;6014.578979471439;6018.368071352511;3357.9496307555405;4951.331570238044;5180.689158650455;5258.658187217857;5259.947400525984;19920.900395498076;20274.898625506925;20280.188792448283;21081.156753729832;21108.683369749466;2024.7266619006393;2090.1639344262267;2100.380387785607;2221.833362497819;2284.9543009139725;12410.751784964341;12957.676058098528;13156.276404797827;13306.201627902357;13660.248686322248;1772.2556526259002;1788.7406326082646;1888.499547619882;1899.8860068395802;1915;65517.672411638116;69290.3070969289;70727.64636176787;71415.92876106914;71885;9843.212542996544;10038.146757525546;10188.337758045704;10207.132393746615;10354.844677329846;14165.220912849893;14269.357878895267;14271.57185284443;14284.07153535017;14288.57134285973;7238.620634516147;7394.852102957911;7518.210587888302;7531.887021694679;7533;7022.192447868459;7070.045543851617;7126.964365178244;7139.428845692332;7222.566646001257;4380.690260368375;4476.888077798016;4480.798364073625;4508.594226519564;4581.839635612738;1663.8336166383265;1753.579141006175;1778.3597904754279;1793.5964408008094;1794.6410717856481;734.7832389445126;868.6589640917792;919.5356345045709;982.3467394182932;985.8570507276405;16348.591285217726;17080.633549316026;17087.658246834995;17324.740128898076;17473.825261747475;882.6072397783054;889.4707648948994;892.3575025981283;924.611663101499;948.0850978805486;9514.238860891111;9553.474558899326;9686.46724430149;9752.073553012595;9875.111239988435;356692;356767.2161639175;364809;366901;368262;68848;72616;73174;75772;76400;827.3753316246075;929.9163075323254;939.492673956056;958.8945216026232;960.9231261499203;12748.362581871055;14808.925955370154;14893.180875051976;15480;16123.11322877253;32767.50848737223;32974.83512582422;34030.31939361149;34188.82905585556;34242.31515369729;44647;45139.322910155715;45747.77126114348;46221.53778462172;46538.76730616325;7530.359919406912;7550.697972081055;7652.961735191288;7733.613319334123;7796.88304675419;10449.477526123816;10592.470376481298;10767.3539587625;11216.327020378803;11345.773084538429;6466.689919776695;6579.868402632016;7101.2898710128575;8241.752747417588;9530.094641009093;9431.528423578657;9449.527523623654;9547.474888881177;9704.85442718374;9783.168430683187;9583.952080239555;9664.95167524186;9705.320627556004;10125.392476451436;10183.43991080468;7348.742794002184;7364.153122390993;7377.741779037708;7510.774676759487;7662.387009039375;9459.195968342492;9751.609935602497;9848;9887.55506002246;9953.800923981624 22:19:18 INFO - PROCESS | 25001 | |3;mootools.html;1283.4224598930484;1402.5021117503288;1441.9927900360497;1529.5182017664436;1740.6692728381606;6243;6967.163940327156;7175.031370764945;7198.532095413794;7363.9631801840915;7155.785326440202;7261.709531618742;7284.4536659750465;7301.963490182555;7314.926850731491;1768.5932235585824;1849.6948003579394;1851.7037274036156;2091.8744875307493;2118.2374345235703;1829.3505805439077;2004.8797072175716;2087.279888438486;2095.758987716409;2099.748030236374;2096.5178009057927;2098.7061811346425;2100.737407824022;2101.600695867788;2102.663573828188;2017.8789272643614;2041.3671761753903;2049.2622655843884;2054.907529161184;2055;1995.6806910894188;1997.5805080933021;2010.6179825833044;2019.676851703735;2020.666590012646;7178.066851309349;7237.059182306319;7241.8551628967125;7245.4928155029165;7245.71017159313;4339.349097635349;4413.514513403523;4417.359482874966;4431.911361772779;4448.488423831269;2695.4609078184244;2739.931501712453;2844.2320573445054;2899.7245261700104;2904.520754075575;840.5251033166303;880.9691660791906;892.2148509311829;912.5163663258418;924.3298608508844;9015.233705135071;9276.721698348993;9292.53537323131;9303.813923721555;9335.346525743133;3934.1541568562325;3954.347532657137;3982.761034337949;3985.5217373914734;4010.3583426651594;8224.177582241728;8226.259636632733;8239.176082391832;8258.958705206556;8264.628091735887;4427.203103441347;4435.600795928382;4439.644828413784;4447.488538818077;4450.46594408673;3557.092941299952;3774.1319496515957;4044.69664775143;4097.078157414618;4100.077482566398;3143.9214019649694;3263.2657652028106;3542.6634469725336;3546.929061418757;3554.608993010767;1107.8172101603307;1148.0528563934788;1153.8903804138592;1155.67063386936;1159.0785325666116;829.1542626520915;829.5810615639064;860.8063185783149;865.5153114256037;899.132337294514;5012.899742005139;5056.595472362203;5106;5108.872278193001;5170.870728231749;421.8481346715241;423.5531514252493;428.84775904553436;429.23810236829877;430.3630626672542;3488.843002064913;3532.452469867175;3538.7876727396438;3539.3983022885654;3669.412893936957;8121.431499794958;8320.583970801557;8336;8337.916620833714;8453.661853525913;8343.207395297437;8415.789605259917;8417.957910210409;8419.831603367898;8425.325973922194;6549.934500654933;6560.540762146699;6610;6614.107095542136;6627.768028118992;4793.23308270675;4854.878628034327;4878.317035615088;4909.877253068702;4937.086638971802;7888.2506161914525;7951.045874494866;7977.680892764457;7987.880181797162;7992.320652744349;7693.422993275639;7925.564093974886;7966.880496792669;8012.158723333966;8048.5975701215875;5664.37691853904;6101.6644084574;6267.185265915402;6348.968255158695;6433.710483028369;6832.897506537505;7092.9645351772915;7096.645167741695;7112.644367781487;7124.928750712634;6354.17395738551;6434.356564343529;6457.903131452938;6518.869622607616;6600.141981542369;6107.755689772359;6225.439710426083;6251.374862513712;6354.841128971812;6375.968120159369;6041.184440100706;6054.969725151523;6102.694865256808;6117.602355846856;6120.908186377119;4296.312589985588;4456.532064133219;4511.390962220058;4558.498565157896;4595.586397224266;4458.2420988431395;4576.382188404523;4595.172868883499;4597.632189424905;4619.35329053926 22:19:18 INFO - PROCESS | 25001 | |4;prototype.html;291.66021584853644;295.3738075280406;295.8979152192493;297.47792623944986;302.4314289136424;4898.975505122474;4928.2361234008695;4932.60768527264;4941.234108713146;4948.8267910623135;4831.830885918994;4860.538248866361;4866.902661946759;4879.658423910328;4881.072596206718;3616.6021737608908;3701.796401197933;3755.830987605564;3827.311084004878;3901.102746368337;3845.788481633509;3850.191459793447;3862.903427414309;3879.941800872975;3882.9710126816276;3601.837917293728;3827.315980968365;3838.0021194489495;3845.365514690073;3854.8265328060306;3724.7392682512236;3819.4409745470603;3838.42423636454;3841.9423708644395;3848.615138486157;3407.897763067112;3408.3694516514274;3410.36908171989;3413.4709120086427;3414.429362493256;4645.442546894395;4648.651351148644;4666.300054991744;4676.18166820805;4678.555537223959;4274.636655884254;4335.436393268855;4355.760433176174;4381.9123617527475;4402.3836662867225;5004.324416203803;5032.874178145539;5033.169527028036;5053.873653158664;5076.796928122871;3678.742488025839;3686.4654625079484;3689.9262014759825;3703.5555733311908;3708.2954238694833;5164.45773193813;5273.31446911899;5296.25852380667;5297.947020529784;5328.49379308965;3782.6217378261954;3784.262068896539;3790.488284081669;3800.1829606634155;3805.448210009533;3862.24686186191;3862.66737975398;3863.072862512977;3868.5937976512623;3877.4571559981623;4324.545922678137;4339.934900976488;4353.281708518059;4361.345798130243;4378.233809083397;4853.854384368475;5030.698158110525;5054.545090941833;5079.339685840817;5086.618503612244;1309.312610879296;1332.094175960337;1335.3523541082568;1337.3112846883862;1346.1990115881035;857.2884505860121;864.6541383446546;901.5717534171216;921.576075005505;922.5986695787354;4493.662975276868;4495.460544734653;4503.797329120198;4507.729536227836;4508.9098218036115;5174.327337446183;5188.0661480933795;5203.687778733288;5225.372955245395;5267.420583735786;4359.997200643869;4468.307412351026;4478.619317357996;4488.483824360241;4635.768211589407;2825.2371859597793;2840.2615320016944;2846.8434236117214;2861.642294713161;2863.7565806906646;4971.37857767779;4974.27872958419;4993.40343021634;5011.9952019192615;5033.7734801934;5313.920291195635;5355.462741252252;5388.67667939917;5447.53695935842;5519.537322609512;4941.690452030215;4963.379577552806;4973.154563724103;5011.649184557119;5011.699298042129;5346.358436987586;5354.116570765936;5356.758945847524;5363.678179309098;5381.354237491526;5124.615653825904;5149.433562308218;5203.8178663746585;5210.713410762444;5219;4903.803847846189;4928.137575924127;4938.259261110862;4951.455339912534;4952.083864485082;3734.7572407793405;3747.2130852521277;3751.5123034006497;3770.227103443842;3771.471993920908;5143.8456846294675;5342.252084708066;5368.597355198298;5392.406835248041;5395.56835453155;5195.6622819518125;5203.0114278287;5203.921941170961;5208.765605547836;5210.426853046238;5233.581313494835;5242.318498595158;5242.921356179737;5244.265802787537;5249.580033597226;4815.759212039454;4963.528464795839;5112.539871411591;5113.360829896263;5146.665466744649;5018.723970181675;5123.28274041627;5164.32863727713;5170.844874653766;5182.429932707324;5060.671056381323;5077.289179514797;5079.949200507947;5089.440161582297;5109.846704598868 22:19:18 INFO - PROCESS | 25001 | |5;yui.html;616.7009000634691;658.2594581096266;668.2882729892667;675.8411773233292;746.9132412340045;11544.191906566544;11711.355875426849;11864.110191735612;11888.821667674993;12458.937705311484;12107.697307567316;12353.567625133122;12748.80876786849;12752.234865908053;12831.42258598363;641.5092454272485;658.4830907737427;797.5135167547805;811.4644334739073;815.2377527012233;781.7381177305609;819.397742659145;820.7414664380731;821.1870248454018;823.9340852731797;861.7457849934284;865.1996902864825;865.6494119881459;865.7143142762905;866.7486428935601;659.4658326755343;820.4134044158395;850.285759961629;856.0968178571604;856.297835774665;621.4096608222162;621.7388696747377;621.791699780574;621.9346968568326;622.3123448589334;13255.933720331434;13282.867171328357;13298.534551290755;13313.600591982256;13314.600561983156;12466;12813.615591532269;12915.741685166338;13265.137766045273;13305.467781288833;11388.088952883749;11471.827922581168;11530.538778448852;11537.134714896416;11814.704632384173;4097.13960068386;4182.56083111272;4203.117345357478;4239.173361194568;4271.355528121696;54607;55469.44530554683;57931.13103303454;58346.41653583495;58559.707201464145;9283.72148835536;9878.851817222747;9909.108180263811;9941.502924853872;10082.949585252027;17468.91265543664;18238.361657341928;18244.270229190948;18250.178741956377;18259.634807304046;6675.098861653614;6731.091302674076;6793.320667933266;6802.387785099365;6900.723971041202;3716.9814150929615;3738.32710112177;3739.7382183247455;3805.448210009533;3871.9612803872164;2778.6665600128113;2841.559558268472;2842.2183899427823;2845.9857700711364;2859.742623163925;516.7777855522162;518.0312815035832;525.4246599973023;527.5067811596169;530.9256704061435;476.64489954983065;490.45314474360833;577.8353169346716;581.3634070692669;615.9137720719103;7607.35337496308;7925.524468531907;7968.681252749941;8003.31971782405;8152;485.77168730696513;551.2117671729385;553.9667619942817;596.9641821490637;601.5819005790934;3540.911477213039;3622.2574372253625;3717.423799311111;3769.4722738816586;3821.885343439701;81427;83581;85236.57381713051;88300;88659;72766.63616681883;73410.63294683493;74226;75642.24357756462;76419.61790191014;65139.34860651428;67255.99116013263;68422;68808.3119168802;70477.64761176264;7812.726554570563;7982.321502672338;8003.639836207268;8165.918340816515;8372;34031.48952765661;36358.27283454263;41408;41604.1679166421;41664.375034375116;42600.57399425966;56063.159052613424;57759.71120144372;58682.34190448124;59222.70388648029;10716.464176790972;10793.08258798011;10835.187360947803;10953.068989136013;11240.775184496428;12186.57346992851;12294.81557776652;12326.260424374566;12518.749625007631;12627.305498197687;11906.166568340133;12083.154179207371;12088.879111208775;12331.075169362155;12536.059795515555;11104.555817767521;11228.775424491301;11347.48936297852;11375.146863985068;11566.248193867372;11101.888981110085;11356.375399353115;12179.634610961686;12460.937695311466;12471.127021108436;9256.629734810802;9357.157855793012;9390.248780097712;9413.341066125306;9723.124918757347;9086.681966131153;9757.609695612096;9898.554565044735;9918.022977501185;9989.789056857693 22:19:18 INFO - PROCESS | 25001 | __end_tp_report 22:19:18 INFO - PROCESS | 25001 | __start_cc_report 22:19:18 INFO - PROCESS | 25001 | _x_x_mozilla_cycle_collect,1025 22:19:18 INFO - PROCESS | 25001 | __end_cc_report 22:19:18 INFO - PROCESS | 25001 | __startTimestamp1464931158832__endTimestamp 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | ------- Summary: start ------- 22:19:18 INFO - PROCESS | 25001 | Number of tests: 6 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | [#0] dojo.html Cycles:165 Average:4764.69 Median:4857.90 stddev:493.14 (10.2%) stddev-sans-first:466.91 22:19:18 INFO - PROCESS | 25001 | Values: 2685.7 2703.7 2709.7 2739.6 2754.7 4688.7 4768.4 4796.1 4821.2 4859.0 4774.4 4797.6 4841.0 4860.4 4862.6 4427.9 4456.1 4477.7 4505.0 4508.0 4457.3 4516.7 4551.9 4588.0 4603.9 4461.3 4501.7 4504.0 4513.6 4549.0 4319.2 4345.0 4366.3 4386.4 4425.6 4291.6 4306.9 4363.6 4384.8 4417.9 4684.2 4689.7 4710.2 4739.4 4743.1 4943.5 5004.7 5061.5 5067.4 5143.5 5173.5 5176.8 5177.9 5183.8 5201.5 4708.2 4809.6 4827.6 4852.2 4857.9 5222.9 5274.0 5339.8 5341.8 5351.8 5049.3 5050.8 5106.3 5112.0 5128.1 4938.4 5038.2 5051.1 5078.8 5108.8 5032.6 5048.1 5068.8 5092.6 5128.1 5057.8 5060.5 5067.0 5069.8 5126.9 4255.8 4304.5 4309.4 4338.8 4395.1 3801.6 3804.2 3840.8 3888.9 3918.5 4499.5 4508.2 4518.3 4551.3 4554.0 5217.3 5221.6 5229.7 5234.3 5283.8 4283.9 4450.8 4484.2 4493.0 4510.5 4679.2 4690.6 4693.7 4697.2 4742.4 4699.1 4825.0 4856.2 4875.4 4915.7 5075.9 5159.0 5183.9 5209.7 5213.3 4802.0 4844.1 4849.8 4852.4 4902.3 4920.1 5045.6 5124.1 5132.6 5141.8 5147.8 5192.5 5216.2 5218.4 5268.8 5051.4 5052.1 5056.7 5062.3 5114.4 4856.7 4979.5 4999.0 5042.8 5062.8 5014.4 5036.1 5054.4 5063.0 5074.3 5116.6 5117.7 5130.5 5170.8 5176.8 4777.2 4800.4 4825.5 4864.0 5008.9 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | [#1] ext.html Cycles:180 Average:48444.91 Median:197741.23 stddev:82922.77 (41.9%) stddev-sans-first:83077.45 22:19:18 INFO - PROCESS | 25001 | Values: 560.2 587.0 602.6 603.6 604.4 28614.6 41467.8 42384.4 42562.0 42632.4 40641.0 41239.8 41296.0 41324.4 41353.6 7524.9 7647.7 7721.8 7730.9 7811.0 7484.6 9936.9 10231.8 10248.9 10497.0 10225.7 10302.4 10309.7 10314.9 10316.1 7466.1 7675.0 10018.9 10086.0 10091.4 7222.7 9824.4 10247.1 10256.3 10275.9 36220.5 36734.6 36810.6 39583.2 39597.8 3107.4 3114.3 3202.0 3219.5 3239.8 30250.0 30448.0 30654.5 30840.2 31240.1 3534.9 3570.1 3572.0 3611.1 3637.5 270855.0 272519.0 273179.0 273363.0 273818.6 59826.0 60178.4 60664.4 60753.0 60811.4 63251.0 63438.2 63444.0 63568.0 63621.4 14915.3 15336.5 15349.8 15351.2 15354.3 10514.2 11384.8 11499.8 11574.5 11681.1 19190.2 19231.2 19244.6 19351.0 19391.0 6974.9 7063.3 7216.2 7222.5 7290.8 819.6 947.4 948.5 951.9 1003.6 27162.6 28144.7 28485.3 28960.3 28977.9 947.7 1008.5 1055.4 1083.8 1137.2 14692.6 15107.8 15254.3 15357.0 15372.5 359555.0 359994.0 360255.0 363324.0 363861.0 50709.0 51411.2 51482.5 51503.5 51559.0 1072.8 1177.1 1215.3 1224.3 1265.2 28632.3 29188.1 29384.9 29649.1 29742.5 43152.8 43227.2 43688.1 43812.3 43911.6 307403.0 309029.0 309217.0 309239.0 310209.0 30974.9 34027.8 38760.2 39052.6 42528.8 43079.4 43301.8 43804.3 43846.3 44168.6 36155.8 36839.4 37402.6 37421.4 38035.2 36992.4 37136.9 37166.3 37562.0 37889.0 35927.5 36261.1 36347.8 37020.0 37154.1 31259.2 33042.0 33216.8 33432.3 33592.3 25122.6 25242.7 25450.6 25750.5 26046.5 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | [#2] jquery.html Cycles:180 Average:23235.27 Median:5070.57 stddev:59926.42 (1181.8%) stddev-sans-first:60069.92 22:19:18 INFO - PROCESS | 25001 | Values: 363.1 363.6 364.1 373.9 381.3 15766.8 17110.5 17158.5 17544.0 17554.7 16782.2 16866.6 17176.7 17511.0 17583.6 5034.3 5199.6 5209.6 5219.1 5219.3 3748.7 3909.3 5106.8 5672.7 6032.3 6040.8 6044.3 6046.8 6090.9 6100.3 5841.5 5951.0 6004.7 6014.6 6018.4 3357.9 4951.3 5180.7 5258.7 5259.9 19920.9 20274.9 20280.2 21081.2 21108.7 2024.7 2090.2 2100.4 2221.8 2285.0 12410.8 12957.7 13156.3 13306.2 13660.2 1772.3 1788.7 1888.5 1899.9 1915.0 65517.7 69290.3 70727.6 71415.9 71885.0 9843.2 10038.1 10188.3 10207.1 10354.8 14165.2 14269.4 14271.6 14284.1 14288.6 7238.6 7394.9 7518.2 7531.9 7533.0 7022.2 7070.0 7127.0 7139.4 7222.6 4380.7 4476.9 4480.8 4508.6 4581.8 1663.8 1753.6 1778.4 1793.6 1794.6 734.8 868.7 919.5 982.3 985.9 16348.6 17080.6 17087.7 17324.7 17473.8 882.6 889.5 892.4 924.6 948.1 9514.2 9553.5 9686.5 9752.1 9875.1 356692.0 356767.2 364809.0 366901.0 368262.0 68848.0 72616.0 73174.0 75772.0 76400.0 827.4 929.9 939.5 958.9 960.9 12748.4 14808.9 14893.2 15480.0 16123.1 32767.5 32974.8 34030.3 34188.8 34242.3 44647.0 45139.3 45747.8 46221.5 46538.8 7530.4 7550.7 7653.0 7733.6 7796.9 10449.5 10592.5 10767.4 11216.3 11345.8 6466.7 6579.9 7101.3 8241.8 9530.1 9431.5 9449.5 9547.5 9704.9 9783.2 9584.0 9665.0 9705.3 10125.4 10183.4 7348.7 7364.2 7377.7 7510.8 7662.4 9459.2 9751.6 9848.0 9887.6 9953.8 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | [#3] mootools.html Cycles:180 Average:4740.47 Median:5034.75 stddev:2550.71 (50.7%) stddev-sans-first:2544.63 22:19:18 INFO - PROCESS | 25001 | Values: 1283.4 1402.5 1442.0 1529.5 1740.7 6243.0 6967.2 7175.0 7198.5 7364.0 7155.8 7261.7 7284.5 7302.0 7314.9 1768.6 1849.7 1851.7 2091.9 2118.2 1829.4 2004.9 2087.3 2095.8 2099.7 2096.5 2098.7 2100.7 2101.6 2102.7 2017.9 2041.4 2049.3 2054.9 2055.0 1995.7 1997.6 2010.6 2019.7 2020.7 7178.1 7237.1 7241.9 7245.5 7245.7 4339.3 4413.5 4417.4 4431.9 4448.5 2695.5 2739.9 2844.2 2899.7 2904.5 840.5 881.0 892.2 912.5 924.3 9015.2 9276.7 9292.5 9303.8 9335.3 3934.2 3954.3 3982.8 3985.5 4010.4 8224.2 8226.3 8239.2 8259.0 8264.6 4427.2 4435.6 4439.6 4447.5 4450.5 3557.1 3774.1 4044.7 4097.1 4100.1 3143.9 3263.3 3542.7 3546.9 3554.6 1107.8 1148.1 1153.9 1155.7 1159.1 829.2 829.6 860.8 865.5 899.1 5012.9 5056.6 5106.0 5108.9 5170.9 421.8 423.6 428.8 429.2 430.4 3488.8 3532.5 3538.8 3539.4 3669.4 8121.4 8320.6 8336.0 8337.9 8453.7 8343.2 8415.8 8418.0 8419.8 8425.3 6549.9 6560.5 6610.0 6614.1 6627.8 4793.2 4854.9 4878.3 4909.9 4937.1 7888.3 7951.0 7977.7 7987.9 7992.3 7693.4 7925.6 7966.9 8012.2 8048.6 5664.4 6101.7 6267.2 6349.0 6433.7 6832.9 7093.0 7096.6 7112.6 7124.9 6354.2 6434.4 6457.9 6518.9 6600.1 6107.8 6225.4 6251.4 6354.8 6376.0 6041.2 6055.0 6102.7 6117.6 6120.9 4296.3 4456.5 4511.4 4558.5 4595.6 4458.2 4576.4 4595.2 4597.6 4619.4 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | [#4] prototype.html Cycles:180 Average:4269.26 Median:4880.37 stddev:1231.81 (25.2%) stddev-sans-first:1198.54 22:19:18 INFO - PROCESS | 25001 | Values: 291.7 295.4 295.9 297.5 302.4 4899.0 4928.2 4932.6 4941.2 4948.8 4831.8 4860.5 4866.9 4879.7 4881.1 3616.6 3701.8 3755.8 3827.3 3901.1 3845.8 3850.2 3862.9 3879.9 3883.0 3601.8 3827.3 3838.0 3845.4 3854.8 3724.7 3819.4 3838.4 3841.9 3848.6 3407.9 3408.4 3410.4 3413.5 3414.4 4645.4 4648.7 4666.3 4676.2 4678.6 4274.6 4335.4 4355.8 4381.9 4402.4 5004.3 5032.9 5033.2 5053.9 5076.8 3678.7 3686.5 3689.9 3703.6 3708.3 5164.5 5273.3 5296.3 5297.9 5328.5 3782.6 3784.3 3790.5 3800.2 3805.4 3862.2 3862.7 3863.1 3868.6 3877.5 4324.5 4339.9 4353.3 4361.3 4378.2 4853.9 5030.7 5054.5 5079.3 5086.6 1309.3 1332.1 1335.4 1337.3 1346.2 857.3 864.7 901.6 921.6 922.6 4493.7 4495.5 4503.8 4507.7 4508.9 5174.3 5188.1 5203.7 5225.4 5267.4 4360.0 4468.3 4478.6 4488.5 4635.8 2825.2 2840.3 2846.8 2861.6 2863.8 4971.4 4974.3 4993.4 5012.0 5033.8 5313.9 5355.5 5388.7 5447.5 5519.5 4941.7 4963.4 4973.2 5011.6 5011.7 5346.4 5354.1 5356.8 5363.7 5381.4 5124.6 5149.4 5203.8 5210.7 5219.0 4903.8 4928.1 4938.3 4951.5 4952.1 3734.8 3747.2 3751.5 3770.2 3771.5 5143.8 5342.3 5368.6 5392.4 5395.6 5195.7 5203.0 5203.9 5208.8 5210.4 5233.6 5242.3 5242.9 5244.3 5249.6 4815.8 4963.5 5112.5 5113.4 5146.7 5018.7 5123.3 5164.3 5170.8 5182.4 5060.7 5077.3 5079.9 5089.4 5109.8 22:19:18 INFO - PROCESS | 25001 | 22:19:18 INFO - PROCESS | 25001 | [#5] yui.html Cycles:180 Average:16397.11 Median:27579.11 stddev:22391.41 (81.2%) stddev-sans-first:22422.87 22:19:18 INFO - PROCESS | 25001 | Values: 616.7 658.3 668.3 675.8 746.9 11544.2 11711.4 11864.1 11888.8 12458.9 12107.7 12353.6 12748.8 12752.2 12831.4 641.5 658.5 797.5 811.5 815.2 781.7 819.4 820.7 821.2 823.9 861.7 865.2 865.6 865.7 866.7 659.5 820.4 850.3 856.1 856.3 621.4 621.7 621.8 621.9 622.3 13255.9 13282.9 13298.5 13313.6 13314.6 12466.0 12813.6 12915.7 13265.1 13305.5 11388.1 11471.8 11530.5 11537.1 11814.7 4097.1 4182.6 4203.1 4239.2 4271.4 54607.0 55469.4 57931.1 58346.4 58559.7 9283.7 9878.9 9909.1 9941.5 10082.9 17468.9 18238.4 18244.3 18250.2 18259.6 6675.1 6731.1 6793.3 6802.4 6900.7 3717.0 3738.3 3739.7 3805.4 3872.0 2778.7 2841.6 2842.2 2846.0 2859.7 516.8 518.0 525.4 527.5 530.9 476.6 490.5 577.8 581.4 615.9 7607.4 7925.5 7968.7 8003.3 8152.0 485.8 551.2 554.0 597.0 601.6 3540.9 3622.3 3717.4 3769.5 3821.9 81427.0 83581.0 85236.6 88300.0 88659.0 72766.6 73410.6 74226.0 75642.2 76419.6 65139.3 67256.0 68422.0 68808.3 70477.6 7812.7 7982.3 8003.6 8165.9 8372.0 34031.5 36358.3 41408.0 41604.2 41664.4 42600.6 56063.2 57759.7 58682.3 59222.7 10716.5 10793.1 10835.2 10953.1 11240.8 12186.6 12294.8 12326.3 12518.7 12627.3 11906.2 12083.2 12088.9 12331.1 12536.1 11104.6 11228.8 11347.5 11375.1 11566.2 11101.9 11356.4 12179.6 12460.9 12471.1 9256.6 9357.2 9390.2 9413.3 9723.1 9086.7 9757.6 9898.6 9918.0 9989.8 22:19:18 INFO - PROCESS | 25001 | -------- Summary: end -------- 22:19:18 INFO - PROCESS | 25001 | 22:19:19 INFO - TEST-INFO | 25001: exit 0 22:19:19 INFO - TEST-OK | dromaeo_css | took 1118946ms 22:19:19 INFO - TEST-START | kraken 22:19:19 INFO - operating with platform_type : linux_ 22:19:19 INFO - Initialising browser for kraken test... 22:19:19 INFO - TEST-INFO | started process 25181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBhWfP4/profile http://localhost:60362/getInfo.html) 22:19:23 INFO - PROCESS | 25181 | __metrics Screen width/height:1600/1200 22:19:23 INFO - PROCESS | 25181 | colorDepth:24 22:19:23 INFO - PROCESS | 25181 | Browser inner width/height: 1024/697 22:19:23 INFO - PROCESS | 25181 | __metrics 22:19:24 ERROR - PROCESS | 25181 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:19:25 INFO - TEST-INFO | 25181: exit 0 22:19:25 INFO - Browser initialized. 22:19:25 INFO - Running cycle 1/1 for kraken test... 22:19:25 INFO - TEST-INFO | started process 25263 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBhWfP4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:19:37 INFO - PROCESS | 25263 | RSS: Main: 157626368 22:19:37 INFO - PROCESS | 25263 | 22:19:39 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60362/tests/kraken/driver.html?testName=audio-beat-detection) 22:19:40 INFO - PROCESS | 25263 | RSS: Main: 215429120 22:19:40 INFO - PROCESS | 25263 | 22:19:43 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60362/tests/kraken/driver.html?testName=audio-dft) 22:19:44 INFO - PROCESS | 25263 | RSS: Main: 202371072 22:19:44 INFO - PROCESS | 25263 | 22:19:47 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60362/tests/kraken/driver.html?testName=audio-fft) 22:19:48 INFO - PROCESS | 25263 | RSS: Main: 270290944 22:19:48 INFO - PROCESS | 25263 | 22:19:50 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60362/tests/kraken/driver.html?testName=audio-oscillator) 22:19:50 INFO - PROCESS | 25263 | RSS: Main: 179916800 22:19:50 INFO - PROCESS | 25263 | 22:19:53 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60362/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:19:53 INFO - PROCESS | 25263 | RSS: Main: 184291328 22:19:53 INFO - PROCESS | 25263 | 22:19:56 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60362/tests/kraken/driver.html?testName=imaging-darkroom) 22:19:57 INFO - PROCESS | 25263 | RSS: Main: 220352512 22:19:57 INFO - PROCESS | 25263 | 22:20:01 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60362/tests/kraken/driver.html?testName=imaging-desaturate) 22:20:01 INFO - PROCESS | 25263 | RSS: Main: 221417472 22:20:01 INFO - PROCESS | 25263 | 22:20:04 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60362/tests/kraken/driver.html?testName=json-parse-financial) 22:20:05 INFO - PROCESS | 25263 | RSS: Main: 221253632 22:20:05 INFO - PROCESS | 25263 | 22:20:06 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60362/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:20:07 INFO - PROCESS | 25263 | RSS: Main: 204107776 22:20:07 INFO - PROCESS | 25263 | 22:20:09 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:20:09 INFO - PROCESS | 25263 | RSS: Main: 210329600 22:20:09 INFO - PROCESS | 25263 | 22:20:12 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:20:12 INFO - PROCESS | 25263 | RSS: Main: 218271744 22:20:12 INFO - PROCESS | 25263 | 22:20:15 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:20:16 INFO - PROCESS | 25263 | RSS: Main: 224731136 22:20:16 INFO - PROCESS | 25263 | 22:20:19 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:20:19 INFO - PROCESS | 25263 | RSS: Main: 224587776 22:20:19 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | Cycle 1(1): loaded http://localhost:60362/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60362/tests/kraken/driver.html?testName=ai-astar) 22:20:21 INFO - PROCESS | 25263 | RSS: Main: 267468800 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | __start_tp_report 22:20:21 INFO - PROCESS | 25263 | _x_x_mozilla_page_load 22:20:21 INFO - PROCESS | 25263 | _x_x_mozilla_page_load_details 22:20:21 INFO - PROCESS | 25263 | |i|pagename|runs| 22:20:21 INFO - PROCESS | 25263 | |0;ai-astar;114;108;114;116;113;114;114;115;113;114 22:20:21 INFO - PROCESS | 25263 | |1;audio-beat-detection;162;128;135;190;131;165;152;132;186;131 22:20:21 INFO - PROCESS | 25263 | |2;audio-dft;160;161;161;162;161;163;160;162;160;161 22:20:21 INFO - PROCESS | 25263 | |3;audio-fft;77;76;77;124;75;76;75;76;117;74 22:20:21 INFO - PROCESS | 25263 | |4;audio-oscillator;85;85;110;85;84;84;125;84;84;123 22:20:21 INFO - PROCESS | 25263 | |5;imaging-gaussian-blur;118;117;118;117;117;117;116;117;117;117 22:20:21 INFO - PROCESS | 25263 | |6;imaging-darkroom;148;147;146;146;146;146;146;147;147;147 22:20:21 INFO - PROCESS | 25263 | |7;imaging-desaturate;95;96;95;95;95;94;95;95;95;94 22:20:21 INFO - PROCESS | 25263 | |8;json-parse-financial;64;70;66;63;67;65;64;63;65;64 22:20:21 INFO - PROCESS | 25263 | |9;json-stringify-tinderbox;64;65;64;65;64;65;64;65;66;64 22:20:21 INFO - PROCESS | 25263 | |10;stanford-crypto-aes;83;87;84;85;91;90;87;92;115;85 22:20:21 INFO - PROCESS | 25263 | |11;stanford-crypto-ccm;131;133;128;130;130;130;132;130;144;132 22:20:21 INFO - PROCESS | 25263 | |12;stanford-crypto-pbkdf2;176;175;175;175;179;171;167;171;169;167 22:20:21 INFO - PROCESS | 25263 | |13;stanford-crypto-sha256-iterative;67;68;67;68;71;66;67;71;67;66 22:20:21 INFO - PROCESS | 25263 | __end_tp_report 22:20:21 INFO - PROCESS | 25263 | __start_cc_report 22:20:21 INFO - PROCESS | 25263 | _x_x_mozilla_cycle_collect,1901 22:20:21 INFO - PROCESS | 25263 | __end_cc_report 22:20:21 INFO - PROCESS | 25263 | __startTimestamp1464931221770__endTimestamp 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | ------- Summary: start ------- 22:20:21 INFO - PROCESS | 25263 | Number of tests: 14 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:2.12 (1.9%) stddev-sans-first:2.24 22:20:21 INFO - PROCESS | 25263 | Values: 114.0 108.0 114.0 116.0 113.0 114.0 114.0 115.0 113.0 114.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#1] audio-beat-detection Cycles:10 Average:151.20 Median:157.00 stddev:23.59 (15.0%) stddev-sans-first:24.70 22:20:21 INFO - PROCESS | 25263 | Values: 162.0 128.0 135.0 190.0 131.0 165.0 152.0 132.0 186.0 131.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 22:20:21 INFO - PROCESS | 25263 | Values: 160.0 161.0 161.0 162.0 161.0 163.0 160.0 162.0 160.0 161.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#3] audio-fft Cycles:10 Average:84.70 Median:76.00 stddev:18.96 (25.0%) stddev-sans-first:19.91 22:20:21 INFO - PROCESS | 25263 | Values: 77.0 76.0 77.0 124.0 75.0 76.0 75.0 76.0 117.0 74.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:84.00 stddev:17.30 (20.6%) stddev-sans-first:17.97 22:20:21 INFO - PROCESS | 25263 | Values: 85.0 85.0 110.0 85.0 84.0 84.0 125.0 84.0 84.0 123.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 22:20:21 INFO - PROCESS | 25263 | Values: 118.0 117.0 118.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.53 22:20:21 INFO - PROCESS | 25263 | Values: 148.0 147.0 146.0 146.0 146.0 146.0 146.0 147.0 147.0 147.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:20:21 INFO - PROCESS | 25263 | Values: 95.0 96.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:2.13 (3.3%) stddev-sans-first:2.22 22:20:21 INFO - PROCESS | 25263 | Values: 64.0 70.0 66.0 63.0 67.0 65.0 64.0 63.0 65.0 64.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 22:20:21 INFO - PROCESS | 25263 | Values: 64.0 65.0 64.0 65.0 64.0 65.0 64.0 65.0 66.0 64.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:87.00 stddev:9.33 (10.7%) stddev-sans-first:9.55 22:20:21 INFO - PROCESS | 25263 | Values: 83.0 87.0 84.0 85.0 91.0 90.0 87.0 92.0 115.0 85.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#11] stanford-crypto-ccm Cycles:10 Average:132.00 Median:131.50 stddev:4.45 (3.4%) stddev-sans-first:4.70 22:20:21 INFO - PROCESS | 25263 | Values: 131.0 133.0 128.0 130.0 130.0 130.0 132.0 130.0 144.0 132.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:175.00 stddev:4.09 (2.3%) stddev-sans-first:4.14 22:20:21 INFO - PROCESS | 25263 | Values: 176.0 175.0 175.0 175.0 179.0 171.0 167.0 171.0 169.0 167.0 22:20:21 INFO - PROCESS | 25263 | 22:20:21 INFO - PROCESS | 25263 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:1.81 (2.7%) stddev-sans-first:1.90 22:20:21 INFO - PROCESS | 25263 | Values: 67.0 68.0 67.0 68.0 71.0 66.0 67.0 71.0 67.0 66.0 22:20:21 INFO - PROCESS | 25263 | -------- Summary: end -------- 22:20:21 INFO - PROCESS | 25263 | 22:20:22 INFO - TEST-INFO | 25263: exit 0 22:20:22 INFO - TEST-OK | kraken | took 63193ms 22:20:22 INFO - SUITE-END | took 1182s 22:20:22 INFO - Completed test suite (00:19:42) 22:20:22 INFO - javascript benchmark 22:20:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2685.6911455182653, 2703.6620422447186, 2709.674839019318, 2739.6027576001493, 2754.7382998615126, 4688.671792974493, 4768.356271903292, 4796.064767370364, 4821.156297647911, 4859.00390419964, 4774.40319960005, 4797.592204662599, 4841.0, 4860.368152140217, 4862.586680132193, 4427.933580996271, 4456.108778244348, 4477.708948918318, 4505.0, 4507.954920450787, 4457.26455134904, 4516.661250406232, 4551.908961820762, 4587.954120458803, 4603.930941035887, 4461.286194208911, 4501.662375321847, 4504.031633198845, 4513.571210734991, 4549.044700612875, 4319.24413227687, 4344.956550434486, 4366.257736184856, 4386.385905973167, 4425.557444255581, 4291.6137547620865, 4306.870793876157, 4363.585459381386, 4384.824607015716, 4417.933730994037, 4684.227102528079, 4689.671722979393, 4710.24636058229, 4739.4075740532435, 4743.09881122589, 4943.456219815817, 5004.674696144739, 5061.519155680241, 5067.417247016567, 5143.5113664201845, 5173.534381905609, 5176.818811341585, 5177.922331165036, 5183.792648294064, 5201.531862132389, 4708.246680531167, 4809.567138957441, 4827.637927155442, 4852.247901575226, 4857.9028419432125, 5222.92165617516, 5274.0, 5339.759710813023, 5341.813036543703, 5351.759170837322, 5049.343585333883, 5050.772715227824, 5106.310648062538, 5112.0, 5128.051310507568, 4938.43208031081, 5038.2190760432195, 5051.06555287273, 5078.771455284521, 5108.821191258363, 5032.572231360376, 5048.066107770074, 5068.797248110108, 5092.592592592584, 5128.1282182029145, 5057.848264552069, 5060.49395060491, 5066.986602679479, 5069.822556210515, 5126.871828204325, 4255.76593287366, 4304.526502084768, 4309.375140604658, 4338.8047537860875, 4395.099004704028, 3801.6388443098413, 3804.1630841214887, 3840.807959602009, 3888.8833334999995, 3918.5101862267215, 4499.460064792246, 4508.211063063951, 4518.299663552155, 4551.271796512542, 4554.0, 5217.34783152106, 5221.634485586049, 5229.712365819841, 5234.2933703949475, 5283.762230699628, 4283.850065247763, 4450.844220452269, 4484.2376795944765, 4492.9775351123035, 4510.50384457716, 4679.227927391942, 4690.624750019991, 4693.671442998957, 4697.201475749131, 4742.383490146259, 4699.08367868276, 4825.010872771003, 4856.1987272099705, 4875.366202393666, 4915.705057696549, 5075.923861142012, 5159.045576568347, 5183.948160518346, 5209.71346575942, 5213.348331458567, 4802.039592081667, 4844.079624871263, 4849.830255941025, 4852.417709874838, 4902.338184345211, 4920.1389756791705, 5045.62157838171, 5124.052050370693, 5132.5893928486375, 5141.767575464864, 5147.819826306061, 5192.532672059533, 5216.243644671577, 5218.425973142876, 5268.78924843002, 5051.368578927634, 5052.065367906949, 5056.696598204119, 5062.265971434048, 5114.360704911886, 4856.732879691651, 4979.452260251442, 4999.0, 5042.84871453857, 5062.82280120194, 5014.373203349582, 5036.143855544639, 5054.368203974504, 5063.0380227756605, 5074.2642316864585, 5116.641835071509, 5117.667351622133, 5130.538251557377, 5170.844874653766, 5176.792928282826, 4777.187878060808, 4800.4479484859685, 4825.469198388104, 4863.95136048635, 5008.899822003613], "value": 4733.744396759039, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.1569637695268, 586.9823905282842, 602.6113157013724, 603.572550917081, 604.4046614085127, 28614.570781438295, 41467.79266103673, 42384.36423453651, 42562.0, 42632.36051459227, 40641.0, 41239.79380103095, 41296.0, 41324.380134297935, 41353.58646413535, 7524.924750752504, 7647.655855486502, 7721.806954826118, 7730.9226907731045, 7811.0, 7484.550926944374, 9936.90063099367, 10231.846522302172, 10248.897511024868, 10497.0, 10225.693229203098, 10302.433366164858, 10309.690709278733, 10314.9484252579, 10316.071553560178, 7466.104067511879, 7675.0, 10018.899811001906, 10086.0, 10091.444970526618, 7222.674979625929, 9824.361416507903, 10247.128994035442, 10256.282060255786, 10275.89724102757, 36220.45669314936, 36734.63265367339, 36810.63189368099, 39583.20833583341, 39597.802010990046, 3107.37852429515, 3114.3304189599116, 3201.983990080035, 3219.4526930421885, 3239.838008099609, 30250.0, 30448.0, 30654.54018189729, 30840.228994275098, 31240.062798116094, 3534.91162720934, 3570.107473131717, 3571.964280357189, 3611.093134741468, 3637.4543818427446, 270855.0, 272519.0, 273179.0, 273363.0, 273818.6309068462, 59826.0, 60178.39821601728, 60664.39335606676, 60753.0, 60811.39188608146, 63251.0, 63438.19370837764, 63444.0, 63568.0, 63621.36378636247, 14915.328810203568, 15336.539903802905, 15349.769753453707, 15351.155686437352, 15354.309056092503, 10514.21143414247, 11384.829227561593, 11499.827502587468, 11574.47914843834, 11681.06551475897, 19190.217418474524, 19231.230750769813, 19244.61510769777, 19351.0, 19391.0, 6974.895376569356, 7063.2583578724525, 7216.242294558996, 7222.494425390278, 7290.781276561712, 819.5779173725537, 947.3652652722692, 948.5304774136846, 951.871497347863, 1003.5684655598163, 27162.5925611116, 28144.71855281462, 28485.287867803057, 28960.27599309992, 28977.855110724733, 947.7207316404059, 1008.5411137932223, 1055.4458909072798, 1083.8320060390652, 1137.1528211482332, 14692.559223223321, 15107.848921510644, 15254.313555890012, 15357.045617682597, 15372.538823835303, 359555.0, 359994.0, 360255.0, 363324.0, 363861.0, 50709.0, 51411.228831567554, 51482.48517514852, 51503.484965150616, 51559.0, 1072.8176210044312, 1177.1171621284036, 1215.295128825279, 1224.3082658298033, 1265.190278221957, 28632.284192895346, 29188.124356269345, 29384.85307573534, 29649.110526684235, 29742.471889888737, 43152.784236079875, 43227.245003050266, 43688.126237475706, 43812.34281485716, 43911.56088439203, 307403.0, 309029.0, 309217.0, 309239.0, 310209.0, 30974.915877944164, 34027.829860850536, 38760.22479550337, 39052.6094739049, 42528.787356063025, 43079.35380969225, 43301.783491082344, 43804.34293485536, 43846.34230486481, 44168.55831441644, 36155.81922090373, 36839.44740828836, 37402.625973739916, 37421.43867842039, 38035.239295214495, 36992.445113322785, 37136.914939357186, 37166.25667486689, 37562.0, 37889.0, 35927.46108808422, 36261.09347266234, 36347.81826090853, 37020.0, 37154.07114822151, 31259.218519536284, 33042.0, 33216.80752385988, 33432.33135337328, 33592.32815343728, 25122.62316065297, 25242.74757252404, 25450.618240726035, 25750.484990299716, 26046.479070418864], "value": 18630.51339709784, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.13935971746963, 363.62183329337483, 364.0679859559526, 373.89904725724074, 381.30031392394955, 15766.76349854753, 17110.486685399443, 17158.48524544266, 17544.0, 17554.736678949826, 16782.244798984044, 16866.578335541617, 17176.742348864776, 17511.0, 17583.560410989732, 5034.320366750488, 5199.558037566811, 5209.635325527214, 5219.086659834533, 5219.269302297663, 3748.6626203641667, 3909.2963266612, 5106.821261255856, 5672.68800215988, 6032.336442991267, 6040.75836966523, 6044.304904935944, 6046.758129674808, 6090.873188460127, 6100.267967843865, 5841.532677385799, 5951.047832346825, 6004.6997650117755, 6014.578979471439, 6018.368071352511, 3357.9496307555405, 4951.331570238044, 5180.689158650455, 5258.658187217857, 5259.947400525984, 19920.900395498076, 20274.898625506925, 20280.188792448283, 21081.156753729832, 21108.683369749466, 2024.7266619006393, 2090.1639344262267, 2100.380387785607, 2221.833362497819, 2284.9543009139725, 12410.751784964341, 12957.676058098528, 13156.276404797827, 13306.201627902357, 13660.248686322248, 1772.2556526259002, 1788.7406326082646, 1888.499547619882, 1899.8860068395802, 1915.0, 65517.672411638116, 69290.3070969289, 70727.64636176787, 71415.92876106914, 71885.0, 9843.212542996544, 10038.146757525546, 10188.337758045704, 10207.132393746615, 10354.844677329846, 14165.220912849893, 14269.357878895267, 14271.57185284443, 14284.07153535017, 14288.57134285973, 7238.620634516147, 7394.852102957911, 7518.210587888302, 7531.887021694679, 7533.0, 7022.192447868459, 7070.045543851617, 7126.964365178244, 7139.428845692332, 7222.566646001257, 4380.690260368375, 4476.888077798016, 4480.798364073625, 4508.594226519564, 4581.839635612738, 1663.8336166383265, 1753.579141006175, 1778.3597904754279, 1793.5964408008094, 1794.6410717856481, 734.7832389445126, 868.6589640917792, 919.5356345045709, 982.3467394182932, 985.8570507276405, 16348.591285217726, 17080.633549316026, 17087.658246834995, 17324.740128898076, 17473.825261747475, 882.6072397783054, 889.4707648948994, 892.3575025981283, 924.611663101499, 948.0850978805486, 9514.238860891111, 9553.474558899326, 9686.46724430149, 9752.073553012595, 9875.111239988435, 356692.0, 356767.2161639175, 364809.0, 366901.0, 368262.0, 68848.0, 72616.0, 73174.0, 75772.0, 76400.0, 827.3753316246075, 929.9163075323254, 939.492673956056, 958.8945216026232, 960.9231261499203, 12748.362581871055, 14808.925955370154, 14893.180875051976, 15480.0, 16123.11322877253, 32767.50848737223, 32974.83512582422, 34030.31939361149, 34188.82905585556, 34242.31515369729, 44647.0, 45139.322910155715, 45747.77126114348, 46221.53778462172, 46538.76730616325, 7530.359919406912, 7550.697972081055, 7652.961735191288, 7733.613319334123, 7796.88304675419, 10449.477526123816, 10592.470376481298, 10767.3539587625, 11216.327020378803, 11345.773084538429, 6466.689919776695, 6579.868402632016, 7101.2898710128575, 8241.752747417588, 9530.094641009093, 9431.528423578657, 9449.527523623654, 9547.474888881177, 9704.85442718374, 9783.168430683187, 9583.952080239555, 9664.95167524186, 9705.320627556004, 10125.392476451436, 10183.43991080468, 7348.742794002184, 7364.153122390993, 7377.741779037708, 7510.774676759487, 7662.387009039375, 9459.195968342492, 9751.609935602497, 9848.0, 9887.55506002246, 9953.800923981624], "value": 8173.342861325153, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.4224598930484, 1402.5021117503288, 1441.9927900360497, 1529.5182017664436, 1740.6692728381606, 6243.0, 6967.163940327156, 7175.031370764945, 7198.532095413794, 7363.9631801840915, 7155.785326440202, 7261.709531618742, 7284.4536659750465, 7301.963490182555, 7314.926850731491, 1768.5932235585824, 1849.6948003579394, 1851.7037274036156, 2091.8744875307493, 2118.2374345235703, 1829.3505805439077, 2004.8797072175716, 2087.279888438486, 2095.758987716409, 2099.748030236374, 2096.5178009057927, 2098.7061811346425, 2100.737407824022, 2101.600695867788, 2102.663573828188, 2017.8789272643614, 2041.3671761753903, 2049.2622655843884, 2054.907529161184, 2055.0, 1995.6806910894188, 1997.5805080933021, 2010.6179825833044, 2019.676851703735, 2020.666590012646, 7178.066851309349, 7237.059182306319, 7241.8551628967125, 7245.4928155029165, 7245.71017159313, 4339.349097635349, 4413.514513403523, 4417.359482874966, 4431.911361772779, 4448.488423831269, 2695.4609078184244, 2739.931501712453, 2844.2320573445054, 2899.7245261700104, 2904.520754075575, 840.5251033166303, 880.9691660791906, 892.2148509311829, 912.5163663258418, 924.3298608508844, 9015.233705135071, 9276.721698348993, 9292.53537323131, 9303.813923721555, 9335.346525743133, 3934.1541568562325, 3954.347532657137, 3982.761034337949, 3985.5217373914734, 4010.3583426651594, 8224.177582241728, 8226.259636632733, 8239.176082391832, 8258.958705206556, 8264.628091735887, 4427.203103441347, 4435.600795928382, 4439.644828413784, 4447.488538818077, 4450.46594408673, 3557.092941299952, 3774.1319496515957, 4044.69664775143, 4097.078157414618, 4100.077482566398, 3143.9214019649694, 3263.2657652028106, 3542.6634469725336, 3546.929061418757, 3554.608993010767, 1107.8172101603307, 1148.0528563934788, 1153.8903804138592, 1155.67063386936, 1159.0785325666116, 829.1542626520915, 829.5810615639064, 860.8063185783149, 865.5153114256037, 899.132337294514, 5012.899742005139, 5056.595472362203, 5106.0, 5108.872278193001, 5170.870728231749, 421.8481346715241, 423.5531514252493, 428.84775904553436, 429.23810236829877, 430.3630626672542, 3488.843002064913, 3532.452469867175, 3538.7876727396438, 3539.3983022885654, 3669.412893936957, 8121.431499794958, 8320.583970801557, 8336.0, 8337.916620833714, 8453.661853525913, 8343.207395297437, 8415.789605259917, 8417.957910210409, 8419.831603367898, 8425.325973922194, 6549.934500654933, 6560.540762146699, 6610.0, 6614.107095542136, 6627.768028118992, 4793.23308270675, 4854.878628034327, 4878.317035615088, 4909.877253068702, 4937.086638971802, 7888.2506161914525, 7951.045874494866, 7977.680892764457, 7987.880181797162, 7992.320652744349, 7693.422993275639, 7925.564093974886, 7966.880496792669, 8012.158723333966, 8048.5975701215875, 5664.37691853904, 6101.6644084574, 6267.185265915402, 6348.968255158695, 6433.710483028369, 6832.897506537505, 7092.9645351772915, 7096.645167741695, 7112.644367781487, 7124.928750712634, 6354.17395738551, 6434.356564343529, 6457.903131452938, 6518.869622607616, 6600.141981542369, 6107.755689772359, 6225.439710426083, 6251.374862513712, 6354.841128971812, 6375.968120159369, 6041.184440100706, 6054.969725151523, 6102.694865256808, 6117.602355846856, 6120.908186377119, 4296.312589985588, 4456.532064133219, 4511.390962220058, 4558.498565157896, 4595.586397224266, 4458.2420988431395, 4576.382188404523, 4595.172868883499, 4597.632189424905, 4619.35329053926], "value": 3815.4324317518717, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.66021584853644, 295.3738075280406, 295.8979152192493, 297.47792623944986, 302.4314289136424, 4898.975505122474, 4928.2361234008695, 4932.60768527264, 4941.234108713146, 4948.8267910623135, 4831.830885918994, 4860.538248866361, 4866.902661946759, 4879.658423910328, 4881.072596206718, 3616.6021737608908, 3701.796401197933, 3755.830987605564, 3827.311084004878, 3901.102746368337, 3845.788481633509, 3850.191459793447, 3862.903427414309, 3879.941800872975, 3882.9710126816276, 3601.837917293728, 3827.315980968365, 3838.0021194489495, 3845.365514690073, 3854.8265328060306, 3724.7392682512236, 3819.4409745470603, 3838.42423636454, 3841.9423708644395, 3848.615138486157, 3407.897763067112, 3408.3694516514274, 3410.36908171989, 3413.4709120086427, 3414.429362493256, 4645.442546894395, 4648.651351148644, 4666.300054991744, 4676.18166820805, 4678.555537223959, 4274.636655884254, 4335.436393268855, 4355.760433176174, 4381.9123617527475, 4402.3836662867225, 5004.324416203803, 5032.874178145539, 5033.169527028036, 5053.873653158664, 5076.796928122871, 3678.742488025839, 3686.4654625079484, 3689.9262014759825, 3703.5555733311908, 3708.2954238694833, 5164.45773193813, 5273.31446911899, 5296.25852380667, 5297.947020529784, 5328.49379308965, 3782.6217378261954, 3784.262068896539, 3790.488284081669, 3800.1829606634155, 3805.448210009533, 3862.24686186191, 3862.66737975398, 3863.072862512977, 3868.5937976512623, 3877.4571559981623, 4324.545922678137, 4339.934900976488, 4353.281708518059, 4361.345798130243, 4378.233809083397, 4853.854384368475, 5030.698158110525, 5054.545090941833, 5079.339685840817, 5086.618503612244, 1309.312610879296, 1332.094175960337, 1335.3523541082568, 1337.3112846883862, 1346.1990115881035, 857.2884505860121, 864.6541383446546, 901.5717534171216, 921.576075005505, 922.5986695787354, 4493.662975276868, 4495.460544734653, 4503.797329120198, 4507.729536227836, 4508.9098218036115, 5174.327337446183, 5188.0661480933795, 5203.687778733288, 5225.372955245395, 5267.420583735786, 4359.997200643869, 4468.307412351026, 4478.619317357996, 4488.483824360241, 4635.768211589407, 2825.2371859597793, 2840.2615320016944, 2846.8434236117214, 2861.642294713161, 2863.7565806906646, 4971.37857767779, 4974.27872958419, 4993.40343021634, 5011.9952019192615, 5033.7734801934, 5313.920291195635, 5355.462741252252, 5388.67667939917, 5447.53695935842, 5519.537322609512, 4941.690452030215, 4963.379577552806, 4973.154563724103, 5011.649184557119, 5011.699298042129, 5346.358436987586, 5354.116570765936, 5356.758945847524, 5363.678179309098, 5381.354237491526, 5124.615653825904, 5149.433562308218, 5203.8178663746585, 5210.713410762444, 5219.0, 4903.803847846189, 4928.137575924127, 4938.259261110862, 4951.455339912534, 4952.083864485082, 3734.7572407793405, 3747.2130852521277, 3751.5123034006497, 3770.227103443842, 3771.471993920908, 5143.8456846294675, 5342.252084708066, 5368.597355198298, 5392.406835248041, 5395.56835453155, 5195.6622819518125, 5203.0114278287, 5203.921941170961, 5208.765605547836, 5210.426853046238, 5233.581313494835, 5242.318498595158, 5242.921356179737, 5244.265802787537, 5249.580033597226, 4815.759212039454, 4963.528464795839, 5112.539871411591, 5113.360829896263, 5146.665466744649, 5018.723970181675, 5123.28274041627, 5164.32863727713, 5170.844874653766, 5182.429932707324, 5060.671056381323, 5077.289179514797, 5079.949200507947, 5089.440161582297, 5109.846704598868], "value": 3877.29944194941, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.7009000634691, 658.2594581096266, 668.2882729892667, 675.8411773233292, 746.9132412340045, 11544.191906566544, 11711.355875426849, 11864.110191735612, 11888.821667674993, 12458.937705311484, 12107.697307567316, 12353.567625133122, 12748.80876786849, 12752.234865908053, 12831.42258598363, 641.5092454272485, 658.4830907737427, 797.5135167547805, 811.4644334739073, 815.2377527012233, 781.7381177305609, 819.397742659145, 820.7414664380731, 821.1870248454018, 823.9340852731797, 861.7457849934284, 865.1996902864825, 865.6494119881459, 865.7143142762905, 866.7486428935601, 659.4658326755343, 820.4134044158395, 850.285759961629, 856.0968178571604, 856.297835774665, 621.4096608222162, 621.7388696747377, 621.791699780574, 621.9346968568326, 622.3123448589334, 13255.933720331434, 13282.867171328357, 13298.534551290755, 13313.600591982256, 13314.600561983156, 12466.0, 12813.615591532269, 12915.741685166338, 13265.137766045273, 13305.467781288833, 11388.088952883749, 11471.827922581168, 11530.538778448852, 11537.134714896416, 11814.704632384173, 4097.13960068386, 4182.56083111272, 4203.117345357478, 4239.173361194568, 4271.355528121696, 54607.0, 55469.44530554683, 57931.13103303454, 58346.41653583495, 58559.707201464145, 9283.72148835536, 9878.851817222747, 9909.108180263811, 9941.502924853872, 10082.949585252027, 17468.91265543664, 18238.361657341928, 18244.270229190948, 18250.178741956377, 18259.634807304046, 6675.098861653614, 6731.091302674076, 6793.320667933266, 6802.387785099365, 6900.723971041202, 3716.9814150929615, 3738.32710112177, 3739.7382183247455, 3805.448210009533, 3871.9612803872164, 2778.6665600128113, 2841.559558268472, 2842.2183899427823, 2845.9857700711364, 2859.742623163925, 516.7777855522162, 518.0312815035832, 525.4246599973023, 527.5067811596169, 530.9256704061435, 476.64489954983065, 490.45314474360833, 577.8353169346716, 581.3634070692669, 615.9137720719103, 7607.35337496308, 7925.524468531907, 7968.681252749941, 8003.31971782405, 8152.0, 485.77168730696513, 551.2117671729385, 553.9667619942817, 596.9641821490637, 601.5819005790934, 3540.911477213039, 3622.2574372253625, 3717.423799311111, 3769.4722738816586, 3821.885343439701, 81427.0, 83581.0, 85236.57381713051, 88300.0, 88659.0, 72766.63616681883, 73410.63294683493, 74226.0, 75642.24357756462, 76419.61790191014, 65139.34860651428, 67255.99116013263, 68422.0, 68808.3119168802, 70477.64761176264, 7812.726554570563, 7982.321502672338, 8003.639836207268, 8165.918340816515, 8372.0, 34031.48952765661, 36358.27283454263, 41408.0, 41604.1679166421, 41664.375034375116, 42600.57399425966, 56063.159052613424, 57759.71120144372, 58682.34190448124, 59222.70388648029, 10716.464176790972, 10793.08258798011, 10835.187360947803, 10953.068989136013, 11240.775184496428, 12186.57346992851, 12294.81557776652, 12326.260424374566, 12518.749625007631, 12627.305498197687, 11906.166568340133, 12083.154179207371, 12088.879111208775, 12331.075169362155, 12536.059795515555, 11104.555817767521, 11228.775424491301, 11347.48936297852, 11375.146863985068, 11566.248193867372, 11101.888981110085, 11356.375399353115, 12179.634610961686, 12460.937695311466, 12471.127021108436, 9256.629734810802, 9357.157855793012, 9390.248780097712, 9413.341066125306, 9723.124918757347, 9086.681966131153, 9757.609695612096, 9898.554565044735, 9918.022977501185, 9989.789056857693], "value": 6419.4929556069965, "unit": "score"}], "name": "dromaeo_css", "value": 6396.016464206387, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 114.0, 116.0, 113.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 128.0, 135.0, 190.0, 131.0, 165.0, 152.0, 132.0, 186.0, 131.0], "value": 151.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 162.0, 161.0, 163.0, 160.0, 162.0, 160.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 124.0, 75.0, 76.0, 75.0, 76.0, 117.0, 74.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 110.0, 85.0, 84.0, 84.0, 125.0, 84.0, 84.0, 123.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 66.0, 63.0, 67.0, 65.0, 64.0, 63.0, 65.0, 64.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 64.0, 65.0, 64.0, 65.0, 66.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 84.0, 85.0, 91.0, 90.0, 87.0, 92.0, 115.0, 85.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 133.0, 128.0, 130.0, 130.0, 130.0, 132.0, 130.0, 144.0, 132.0], "value": 132.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 175.0, 175.0, 175.0, 179.0, 171.0, 167.0, 171.0, 169.0, 167.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 71.0, 66.0, 67.0, 71.0, 67.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1555.8999999999999, "alertThreshold": 2.0}]} 22:20:23 INFO - Return code: 0 22:20:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:20:23 ERROR - # TBPL SUCCESS # 22:20:23 INFO - Running post-action listener: _resource_record_post_action 22:20:23 INFO - ##### 22:20:23 INFO - ##### Finished run-tests step (success) 22:20:23 INFO - ##### 22:20:23 INFO - Running post-run listener: _resource_record_post_run 22:20:23 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 523141120; Read time: 0; Write time: 337692 22:20:23 INFO - TinderboxPrint: CPU usage
13.1% 22:20:23 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:20:23 INFO - TinderboxPrint: I/O write bytes / time
523,141,120 / 337,692 22:20:23 INFO - TinderboxPrint: CPU idle
8,315.9 (86.9%) 22:20:23 INFO - TinderboxPrint: CPU user
1,213.6 (12.7%) 22:20:23 INFO - TinderboxPrint: Swap in / out
0 / 0 22:20:23 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107528192; Read time: 0; Write time: 77308 22:20:23 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 285679616; Read time: 0; Write time: 170340 22:20:23 INFO - Running post-run listener: _upload_blobber_files 22:20:23 INFO - Blob upload gear active. 22:20:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:20:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:20:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:20:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:20:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:24 INFO - (blobuploader) - INFO - Done attempting. 22:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:20:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:25 INFO - (blobuploader) - INFO - Done attempting. 22:20:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:20:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:20:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:30 INFO - (blobuploader) - INFO - Done attempting. 22:20:30 INFO - (blobuploader) - INFO - Iteration through files over. 22:20:30 INFO - Return code: 0 22:20:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:20:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:20:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/208cfe794c28abbd3cdafa48fc5c9b81639d14081ccc7e5706c97c3c2a77460be23ee120fadc8a011a43b3976c07c0ca9b77c8b51574567326628f035482ac06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/321e65abf9c9223c08db050f88fc73fbb97b7de6cadd2d5955a764a007d71a61dd837e8f07f886d7fb2a11ea383d1f2f8c47f9f96b681323ceeed986a349fc69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a3e05e0ddc7a53839c85151719edcd424bf19a4dc71ebb0d852b8554238ff31390bdb86cb9a62780b4600a058fafd258a881495c5c5880933a2c7d7ce421f19"} 22:20:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:20:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:20:30 INFO - Contents: 22:20:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/208cfe794c28abbd3cdafa48fc5c9b81639d14081ccc7e5706c97c3c2a77460be23ee120fadc8a011a43b3976c07c0ca9b77c8b51574567326628f035482ac06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/321e65abf9c9223c08db050f88fc73fbb97b7de6cadd2d5955a764a007d71a61dd837e8f07f886d7fb2a11ea383d1f2f8c47f9f96b681323ceeed986a349fc69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a3e05e0ddc7a53839c85151719edcd424bf19a4dc71ebb0d852b8554238ff31390bdb86cb9a62780b4600a058fafd258a881495c5c5880933a2c7d7ce421f19"} 22:20:30 INFO - Running post-run listener: copy_logs_to_upload_dir 22:20:30 INFO - Copying logs to upload dir... 22:20:30 INFO - mkdir: /builds/slave/test/build/upload/logs 22:20:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.099848 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-06-02 22:20:29.858778) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:29.861837) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/208cfe794c28abbd3cdafa48fc5c9b81639d14081ccc7e5706c97c3c2a77460be23ee120fadc8a011a43b3976c07c0ca9b77c8b51574567326628f035482ac06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/321e65abf9c9223c08db050f88fc73fbb97b7de6cadd2d5955a764a007d71a61dd837e8f07f886d7fb2a11ea383d1f2f8c47f9f96b681323ceeed986a349fc69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a3e05e0ddc7a53839c85151719edcd424bf19a4dc71ebb0d852b8554238ff31390bdb86cb9a62780b4600a058fafd258a881495c5c5880933a2c7d7ce421f19"} build_url:https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/C1-aLizpRjedHF4l9pHxoA/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/Fx-Team-Non-PGO/sha512/208cfe794c28abbd3cdafa48fc5c9b81639d14081ccc7e5706c97c3c2a77460be23ee120fadc8a011a43b3976c07c0ca9b77c8b51574567326628f035482ac06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/321e65abf9c9223c08db050f88fc73fbb97b7de6cadd2d5955a764a007d71a61dd837e8f07f886d7fb2a11ea383d1f2f8c47f9f96b681323ceeed986a349fc69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a3e05e0ddc7a53839c85151719edcd424bf19a4dc71ebb0d852b8554238ff31390bdb86cb9a62780b4600a058fafd258a881495c5c5880933a2c7d7ce421f19"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:29.886447) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:29.886761) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1464912988.624383-638981012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:20:29.970281) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:20:29.970606) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:20:29.970907) ========= ========= Total master_lag: 0.17 =========