builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1463734281.0 results: success (0) buildid: 20160519233025 builduid: df97911c063843d686b3492b79762233 revision: 00ed91d9c2c83fec8c61565ef1f73ed7a7df724a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:20.995863) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:20.996292) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:20.997013) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.016562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.017188) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 01:51:21-- 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% 43.1M=0s 2016-05-20 01:51:21 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138143 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.170872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.171229) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018777 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.221949) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.222338) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 00ed91d9c2c83fec8c61565ef1f73ed7a7df724a --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 00ed91d9c2c83fec8c61565ef1f73ed7a7df724a --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=a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 01:51:21,377 truncating revision to first 12 chars 2016-05-20 01:51:21,378 Setting DEBUG logging. 2016-05-20 01:51:21,378 attempt 1/10 2016-05-20 01:51:21,378 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/00ed91d9c2c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 01:51:21,628 unpacking tar archive at: fx-team-00ed91d9c2c8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.538157 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.774281) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.774669) ========= script_repo_revision: 00ed91d9c2c83fec8c61565ef1f73ed7a7df724a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.775133) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.775449) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 01:51:21.785648) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-20 01:51:21.785979) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:51:22 INFO - MultiFileLogger online at 20160520 01:51:22 in /builds/slave/test 01:51:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:51:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:51:22 INFO - {'append_to_log': False, 01:51:22 INFO - 'base_work_dir': '/builds/slave/test', 01:51:22 INFO - 'blob_upload_branch': 'Fx-Team', 01:51:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:51:22 INFO - 'branch': 'Fx-Team', 01:51:22 INFO - 'buildbot_json_path': 'buildprops.json', 01:51:22 INFO - 'config_files': ('talos/linux_config.py',), 01:51:22 INFO - 'default_actions': ('clobber', 01:51:22 INFO - 'read-buildbot-config', 01:51:22 INFO - 'download-and-extract', 01:51:22 INFO - 'populate-webroot', 01:51:22 INFO - 'create-virtualenv', 01:51:22 INFO - 'install', 01:51:22 INFO - 'run-tests'), 01:51:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:51:22 INFO - 'download_minidump_stackwalk': True, 01:51:22 INFO - 'download_symbols': 'ondemand', 01:51:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:51:22 INFO - 'tooltool.py': '/tools/tooltool.py', 01:51:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:51:22 INFO - '/tools/misc-python/virtualenv.py')}, 01:51:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:51:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:51:22 INFO - 'installer_path': 'installer.exe', 01:51:22 INFO - 'log_level': 'info', 01:51:22 INFO - 'log_name': 'talos', 01:51:22 INFO - 'log_to_console': True, 01:51:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:51:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:51:22 INFO - 'opt_config_files': (), 01:51:22 INFO - 'pip_index': False, 01:51:22 INFO - 'sps_profile': False, 01:51:22 INFO - 'sps_profile_interval': 0, 01:51:22 INFO - 'suite': 'dromaeojs', 01:51:22 INFO - 'system_bits': '32', 01:51:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:51:22 INFO - 'title': 'talos-linux64-ix-064', 01:51:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:51:22 INFO - 'use_talos_json': True, 01:51:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:51:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:51:22 INFO - 'work_dir': 'build'} 01:51:22 INFO - ##### 01:51:22 INFO - ##### Running clobber step. 01:51:22 INFO - ##### 01:51:22 INFO - Running pre-action listener: _resource_record_pre_action 01:51:22 INFO - Running main action method: clobber 01:51:22 INFO - rmtree: /builds/slave/test/build 01:51:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:51:22 INFO - Running post-action listener: _resource_record_post_action 01:51:22 INFO - ##### 01:51:22 INFO - ##### Finished clobber step (success) 01:51:22 INFO - ##### 01:51:22 INFO - ##### 01:51:22 INFO - ##### Running read-buildbot-config step. 01:51:22 INFO - ##### 01:51:22 INFO - Running pre-action listener: _resource_record_pre_action 01:51:22 INFO - Running main action method: read_buildbot_config 01:51:22 INFO - Using buildbot properties: 01:51:22 INFO - { 01:51:22 INFO - "project": "", 01:51:22 INFO - "product": "firefox", 01:51:22 INFO - "script_repo_revision": "production", 01:51:22 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 01:51:22 INFO - "repository": "", 01:51:22 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 01:51:22 INFO - "buildid": "20160519233025", 01:51:22 INFO - "pgo_build": "True", 01:51:22 INFO - "basedir": "/builds/slave/test", 01:51:22 INFO - "buildnumber": 109, 01:51:22 INFO - "slavename": "talos-linux64-ix-064", 01:51:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:51:22 INFO - "platform": "ubuntu64_hw", 01:51:22 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 01:51:22 INFO - "branch": "fx-team", 01:51:22 INFO - "repo_path": "integration/fx-team", 01:51:22 INFO - "revision": "00ed91d9c2c83fec8c61565ef1f73ed7a7df724a", 01:51:22 INFO - "stage_platform": "linux64-pgo", 01:51:22 INFO - "builduid": "df97911c063843d686b3492b79762233", 01:51:22 INFO - "slavebuilddir": "test" 01:51:22 INFO - } 01:51:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 01:51:22 INFO - Running post-action listener: _resource_record_post_action 01:51:22 INFO - ##### 01:51:22 INFO - ##### Finished read-buildbot-config step (success) 01:51:22 INFO - ##### 01:51:22 INFO - ##### 01:51:22 INFO - ##### Running download-and-extract step. 01:51:22 INFO - ##### 01:51:22 INFO - Running pre-action listener: _resource_record_pre_action 01:51:22 INFO - Running main action method: download_and_extract 01:51:22 INFO - mkdir: /builds/slave/test/build/tests 01:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:51:22 INFO - https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:51:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:51:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:51:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:51:37 INFO - Downloaded 1361 bytes. 01:51:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 01:51:37 INFO - Using the following test package requirements: 01:51:37 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:51:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:51:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:51:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'jsshell-linux-x86_64.zip'], 01:51:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:51:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 01:51:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:51:37 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:51:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:51:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 01:51:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:51:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:51:37 INFO - https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:51:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:51:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:51:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:51:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:51:38 INFO - Downloaded 24387199 bytes. 01:51:38 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'] 01:51:38 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 01:51:40 INFO - Return code: 0 01:51:40 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 01:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:51:40 INFO - https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:51:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:51:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 01:51:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:51:59 INFO - Downloaded 24387199 bytes. 01:51:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:51:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:52:00 INFO - Return code: 0 01:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:00 INFO - https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:52:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:52:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 01:52:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:52:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:52:01 INFO - Downloaded 10840359 bytes. 01:52:01 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'] 01:52:01 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 01:52:01 INFO - Return code: 0 01:52: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')]} 01:52:01 INFO - https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:52:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:52:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:52:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:52:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XtwNK2xGROyY20UCLnhRBg/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 01:52:03 INFO - Downloaded 58576791 bytes. 01:52:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:52:03 INFO - mkdir: /builds/slave/test/properties 01:52:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:52:03 INFO - Writing to file /builds/slave/test/properties/build_url 01:52:03 INFO - Contents: 01:52:03 INFO - build_url:https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 01:52:04 INFO - Running post-action listener: _resource_record_post_action 01:52:04 INFO - Running post-action listener: set_extra_try_arguments 01:52:04 INFO - ##### 01:52:04 INFO - ##### Finished download-and-extract step (success) 01:52:04 INFO - ##### 01:52:04 INFO - ##### 01:52:04 INFO - ##### Running populate-webroot step. 01:52:04 INFO - ##### 01:52:04 INFO - Running pre-action listener: _resource_record_pre_action 01:52:04 INFO - Running main action method: populate_webroot 01:52:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:52:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:52:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:52:04 INFO - '--tppagecycles', 01:52:04 INFO - '7'], 01:52:04 INFO - 'tests': ['tsvgm']}}, 01:52:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:52:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:52:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:52:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:52:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['damp', 'tps']}, 01:52:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'tests': ['damp', 'tps']}, 01:52:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['dromaeo_dom']}, 01:52:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:52:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['a11yr', 01:52:04 INFO - 'ts_paint', 01:52:04 INFO - 'tpaint', 01:52:04 INFO - 'sessionrestore', 01:52:04 INFO - 'sessionrestore_no_auto_restore', 01:52:04 INFO - 'tabpaint']}, 01:52:04 INFO - 'other-e10s': {'tests': ['a11yr', 01:52:04 INFO - 'ts_paint', 01:52:04 INFO - 'tpaint', 01:52:04 INFO - 'sessionrestore', 01:52:04 INFO - 'sessionrestore_no_auto_restore', 01:52:04 INFO - 'tabpaint']}, 01:52:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:52:04 INFO - 'ts_paint', 01:52:04 INFO - 'tpaint', 01:52:04 INFO - 'sessionrestore', 01:52:04 INFO - 'sessionrestore_no_auto_restore', 01:52:04 INFO - 'tabpaint']}, 01:52:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:52:04 INFO - 'ts_paint', 01:52:04 INFO - 'tpaint', 01:52:04 INFO - 'sessionrestore', 01:52:04 INFO - 'sessionrestore_no_auto_restore', 01:52:04 INFO - 'tabpaint']}, 01:52:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['a11yr', 01:52:04 INFO - 'ts_paint', 01:52:04 INFO - 'tpaint', 01:52:04 INFO - 'sessionrestore', 01:52:04 INFO - 'sessionrestore_no_auto_restore', 01:52:04 INFO - 'tabpaint']}, 01:52:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['a11yr', 01:52:04 INFO - 'ts_paint', 01:52:04 INFO - 'tpaint', 01:52:04 INFO - 'sessionrestore', 01:52:04 INFO - 'sessionrestore_no_auto_restore', 01:52:04 INFO - 'tabpaint']}, 01:52:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['tsvgx', 01:52:04 INFO - 'tsvgr_opacity', 01:52:04 INFO - 'tart', 01:52:04 INFO - 'tscrollx', 01:52:04 INFO - 'cart']}, 01:52:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:52:04 INFO - 'tsvgr_opacity', 01:52:04 INFO - 'tart', 01:52:04 INFO - 'tscrollx', 01:52:04 INFO - 'cart']}, 01:52:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'talos_options': ['--disable-e10s'], 01:52:04 INFO - 'tests': ['tp5o']}, 01:52:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'tests': ['tp5o']}, 01:52:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'talos_options': ['--disable-e10s', 01:52:04 INFO - '--xperf_path', 01:52:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:04 INFO - 'tests': ['tp5n']}, 01:52:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:04 INFO - 'talos_options': ['--xperf_path', 01:52:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:04 INFO - 'tests': ['tp5n']}}, 01:52:04 INFO - 'talos.zip': {'path': '', 01:52:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:52:04 INFO - Running post-action listener: _resource_record_post_action 01:52:04 INFO - ##### 01:52:04 INFO - ##### Finished populate-webroot step (success) 01:52:04 INFO - ##### 01:52:04 INFO - ##### 01:52:04 INFO - ##### Running create-virtualenv step. 01:52:04 INFO - ##### 01:52:04 INFO - Running pre-action listener: _resource_record_pre_action 01:52:04 INFO - Running main action method: create_virtualenv 01:52:04 INFO - Creating virtualenv /builds/slave/test/build/venv 01:52:04 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 01:52:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:52:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:52:04 INFO - Using real prefix '/usr' 01:52:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:52:05 INFO - Installing distribute.............................................................................................................................................................................................done. 01:52:06 INFO - Installing pip.................done. 01:52:07 INFO - Return code: 0 01:52:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:07 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 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:07 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 01:52:07 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 01:52:07 INFO - Using env: {'DISPLAY': ':0', 01:52:07 INFO - 'HOME': '/home/cltbld', 01:52:07 INFO - 'LANG': 'en_US.UTF-8', 01:52:07 INFO - 'LANGUAGE': 'en_US:en', 01:52:07 INFO - 'LOGNAME': 'cltbld', 01:52:07 INFO - 'MAIL': '/var/mail/cltbld', 01:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:07 INFO - 'MOZ_NO_REMOTE': '1', 01:52:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:07 INFO - 'NO_EM_RESTART': '1', 01:52:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:07 INFO - 'PWD': '/builds/slave/test', 01:52:07 INFO - 'SHELL': '/bin/bash', 01:52:07 INFO - 'SHLVL': '1', 01:52:07 INFO - 'TERM': 'linux', 01:52:07 INFO - 'TMOUT': '86400', 01:52:07 INFO - 'USER': 'cltbld', 01:52:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:07 INFO - '_': '/tools/buildbot/bin/python'} 01:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:07 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 01:52:07 INFO - Cleaning up... 01:52:07 INFO - Return code: 0 01:52:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:07 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 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:07 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 01:52:07 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 01:52:07 INFO - Using env: {'DISPLAY': ':0', 01:52:07 INFO - 'HOME': '/home/cltbld', 01:52:07 INFO - 'LANG': 'en_US.UTF-8', 01:52:07 INFO - 'LANGUAGE': 'en_US:en', 01:52:07 INFO - 'LOGNAME': 'cltbld', 01:52:07 INFO - 'MAIL': '/var/mail/cltbld', 01:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:07 INFO - 'MOZ_NO_REMOTE': '1', 01:52:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:07 INFO - 'NO_EM_RESTART': '1', 01:52:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:07 INFO - 'PWD': '/builds/slave/test', 01:52:07 INFO - 'SHELL': '/bin/bash', 01:52:07 INFO - 'SHLVL': '1', 01:52:07 INFO - 'TERM': 'linux', 01:52:07 INFO - 'TMOUT': '86400', 01:52:07 INFO - 'USER': 'cltbld', 01:52:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:07 INFO - '_': '/tools/buildbot/bin/python'} 01:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:07 INFO - Downloading/unpacking psutil>=3.1.1 01:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:52:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:09 INFO - Installing collected packages: psutil 01:52:09 INFO - Running setup.py install for psutil 01:52:09 INFO - building 'psutil._psutil_linux' extension 01:52:09 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 01:52:09 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 01:52:09 INFO - building 'psutil._psutil_posix' extension 01:52:09 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 01:52:09 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 01:52:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:09 INFO - Successfully installed psutil 01:52:09 INFO - Cleaning up... 01:52:09 INFO - Return code: 0 01:52:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:52:09 INFO - Using env: {'DISPLAY': ':0', 01:52:09 INFO - 'HOME': '/home/cltbld', 01:52:09 INFO - 'LANG': 'en_US.UTF-8', 01:52:09 INFO - 'LANGUAGE': 'en_US:en', 01:52:09 INFO - 'LOGNAME': 'cltbld', 01:52:09 INFO - 'MAIL': '/var/mail/cltbld', 01:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:09 INFO - 'MOZ_NO_REMOTE': '1', 01:52:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:09 INFO - 'NO_EM_RESTART': '1', 01:52:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:09 INFO - 'PWD': '/builds/slave/test', 01:52:09 INFO - 'SHELL': '/bin/bash', 01:52:09 INFO - 'SHLVL': '1', 01:52:09 INFO - 'TERM': 'linux', 01:52:09 INFO - 'TMOUT': '86400', 01:52:09 INFO - 'USER': 'cltbld', 01:52:09 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:09 INFO - '_': '/tools/buildbot/bin/python'} 01:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:52:10 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 01:52:10 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 01:52:10 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 01:52:10 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 01:52:10 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 01:52:10 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 01:52:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:52:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:52:12 INFO - Installing collected packages: mozsystemmonitor 01:52:12 INFO - Running setup.py install for mozsystemmonitor 01:52:12 INFO - Successfully installed mozsystemmonitor 01:52:12 INFO - Cleaning up... 01:52:12 INFO - Return code: 0 01:52:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:52:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:52:12 INFO - Using env: {'DISPLAY': ':0', 01:52:12 INFO - 'HOME': '/home/cltbld', 01:52:12 INFO - 'LANG': 'en_US.UTF-8', 01:52:12 INFO - 'LANGUAGE': 'en_US:en', 01:52:12 INFO - 'LOGNAME': 'cltbld', 01:52:12 INFO - 'MAIL': '/var/mail/cltbld', 01:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:12 INFO - 'MOZ_NO_REMOTE': '1', 01:52:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:12 INFO - 'NO_EM_RESTART': '1', 01:52:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:12 INFO - 'PWD': '/builds/slave/test', 01:52:12 INFO - 'SHELL': '/bin/bash', 01:52:12 INFO - 'SHLVL': '1', 01:52:12 INFO - 'TERM': 'linux', 01:52:12 INFO - 'TMOUT': '86400', 01:52:12 INFO - 'USER': 'cltbld', 01:52:12 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:12 INFO - '_': '/tools/buildbot/bin/python'} 01:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:12 INFO - Downloading/unpacking blobuploader==1.2.4 01:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:14 INFO - Downloading blobuploader-1.2.4.tar.gz 01:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:52:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:52:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:15 INFO - Downloading docopt-0.6.1.tar.gz 01:52:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:52:15 INFO - Installing collected packages: blobuploader, requests, docopt 01:52:15 INFO - Running setup.py install for blobuploader 01:52:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:52:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:52:15 INFO - Running setup.py install for requests 01:52:15 INFO - Running setup.py install for docopt 01:52:15 INFO - Successfully installed blobuploader requests docopt 01:52:15 INFO - Cleaning up... 01:52:15 INFO - Return code: 0 01:52:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:15 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')]} 01:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:15 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')]} 01:52:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:15 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 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:15 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 01:52:15 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 01:52:15 INFO - Using env: {'DISPLAY': ':0', 01:52:15 INFO - 'HOME': '/home/cltbld', 01:52:15 INFO - 'LANG': 'en_US.UTF-8', 01:52:15 INFO - 'LANGUAGE': 'en_US:en', 01:52:15 INFO - 'LOGNAME': 'cltbld', 01:52:15 INFO - 'MAIL': '/var/mail/cltbld', 01:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:15 INFO - 'MOZ_NO_REMOTE': '1', 01:52:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:15 INFO - 'NO_EM_RESTART': '1', 01:52:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:15 INFO - 'PWD': '/builds/slave/test', 01:52:15 INFO - 'SHELL': '/bin/bash', 01:52:15 INFO - 'SHLVL': '1', 01:52:15 INFO - 'TERM': 'linux', 01:52:15 INFO - 'TMOUT': '86400', 01:52:15 INFO - 'USER': 'cltbld', 01:52:15 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:15 INFO - '_': '/tools/buildbot/bin/python'} 01:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:15 INFO - Running setup.py (path:/tmp/pip-sweCYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:15 INFO - Running setup.py (path:/tmp/pip-n_Zpxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:16 INFO - Running setup.py (path:/tmp/pip-baejkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:16 INFO - Running setup.py (path:/tmp/pip-1Zu0xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:16 INFO - Running setup.py (path:/tmp/pip-D0k4HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:16 INFO - Running setup.py (path:/tmp/pip-Qzaeqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:16 INFO - Running setup.py (path:/tmp/pip-1XK0Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:16 INFO - Running setup.py (path:/tmp/pip-9fAYTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:16 INFO - Running setup.py (path:/tmp/pip-X2kN4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:16 INFO - Running setup.py (path:/tmp/pip-VXHVlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:16 INFO - Running setup.py (path:/tmp/pip-7Yr2cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:16 INFO - Running setup.py (path:/tmp/pip-DKR4Fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:16 INFO - Running setup.py (path:/tmp/pip-nZRgr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:16 INFO - Running setup.py (path:/tmp/pip-y3GZm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:16 INFO - Running setup.py (path:/tmp/pip-bO6K1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:16 INFO - Running setup.py (path:/tmp/pip-vuWjRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:17 INFO - Running setup.py (path:/tmp/pip-zyQkXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:52:17 INFO - Running setup.py install for manifestparser 01:52:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Running setup.py install for mozcrash 01:52:17 INFO - Running setup.py install for mozdebug 01:52:17 INFO - Running setup.py install for mozdevice 01:52:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Running setup.py install for mozfile 01:52:17 INFO - Running setup.py install for mozhttpd 01:52:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Running setup.py install for mozinfo 01:52:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Running setup.py install for mozInstall 01:52:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:52:17 INFO - Running setup.py install for mozleak 01:52:18 INFO - Running setup.py install for mozlog 01:52:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:52:18 INFO - Running setup.py install for moznetwork 01:52:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:52:18 INFO - Running setup.py install for mozprocess 01:52:18 INFO - Running setup.py install for mozprofile 01:52:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:52:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:52:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:52:18 INFO - Running setup.py install for mozrunner 01:52:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:52:18 INFO - Running setup.py install for mozscreenshot 01:52:18 INFO - Running setup.py install for moztest 01:52:18 INFO - Running setup.py install for mozversion 01:52:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:52:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:52:18 INFO - Cleaning up... 01:52:18 INFO - Return code: 0 01:52:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:18 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 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:18 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 01:52:18 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 01:52:18 INFO - Using env: {'DISPLAY': ':0', 01:52:18 INFO - 'HOME': '/home/cltbld', 01:52:18 INFO - 'LANG': 'en_US.UTF-8', 01:52:18 INFO - 'LANGUAGE': 'en_US:en', 01:52:18 INFO - 'LOGNAME': 'cltbld', 01:52:18 INFO - 'MAIL': '/var/mail/cltbld', 01:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:18 INFO - 'MOZ_NO_REMOTE': '1', 01:52:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:18 INFO - 'NO_EM_RESTART': '1', 01:52:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:18 INFO - 'PWD': '/builds/slave/test', 01:52:18 INFO - 'SHELL': '/bin/bash', 01:52:18 INFO - 'SHLVL': '1', 01:52:18 INFO - 'TERM': 'linux', 01:52:18 INFO - 'TMOUT': '86400', 01:52:18 INFO - 'USER': 'cltbld', 01:52:18 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:18 INFO - '_': '/tools/buildbot/bin/python'} 01:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:19 INFO - Running setup.py (path:/tmp/pip-OBZiOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:19 INFO - Running setup.py (path:/tmp/pip-rTupS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:19 INFO - Running setup.py (path:/tmp/pip-g28tC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:19 INFO - Running setup.py (path:/tmp/pip-CBwSGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:19 INFO - Running setup.py (path:/tmp/pip-r5Jf5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:19 INFO - Running setup.py (path:/tmp/pip-tzBFc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:19 INFO - Running setup.py (path:/tmp/pip-Ly_QvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:19 INFO - Running setup.py (path:/tmp/pip-Pv5DKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:19 INFO - Running setup.py (path:/tmp/pip-TX2bKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:19 INFO - Running setup.py (path:/tmp/pip-Wssu57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:19 INFO - Running setup.py (path:/tmp/pip-HWGIoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:19 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)) 01:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:19 INFO - Running setup.py (path:/tmp/pip-i0Otz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:20 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)) 01:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:20 INFO - Running setup.py (path:/tmp/pip-f9CA51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:20 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)) 01:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:20 INFO - Running setup.py (path:/tmp/pip-cUyx2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:20 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)) 01:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:20 INFO - Running setup.py (path:/tmp/pip-TRiUy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:20 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)) 01:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:20 INFO - Running setup.py (path:/tmp/pip-4n7bJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:20 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)) 01:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:20 INFO - Running setup.py (path:/tmp/pip-sZanrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:20 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)) 01:52:20 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)) 01:52:20 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)) 01:52:20 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)) 01:52:20 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)) 01:52:20 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)) 01:52:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:22 INFO - Downloading blessings-1.6.tar.gz 01:52:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:52:22 INFO - Installing collected packages: blessings 01:52:22 INFO - Running setup.py install for blessings 01:52:22 INFO - Successfully installed blessings 01:52:22 INFO - Cleaning up... 01:52:22 INFO - Return code: 0 01:52:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:22 INFO - Reading from file tmpfile_stdout 01:52:22 INFO - Current package versions: 01:52:22 INFO - argparse == 1.2.1 01:52:22 INFO - blessings == 1.6 01:52:22 INFO - blobuploader == 1.2.4 01:52:22 INFO - docopt == 0.6.1 01:52:22 INFO - manifestparser == 1.1 01:52:22 INFO - mozInstall == 1.12 01:52:22 INFO - mozcrash == 0.17 01:52:22 INFO - mozdebug == 0.1 01:52:22 INFO - mozdevice == 0.48 01:52:22 INFO - mozfile == 1.2 01:52:22 INFO - mozhttpd == 0.7 01:52:22 INFO - mozinfo == 0.9 01:52:22 INFO - mozleak == 0.1 01:52:22 INFO - mozlog == 3.2 01:52:22 INFO - moznetwork == 0.27 01:52:22 INFO - mozprocess == 0.22 01:52:22 INFO - mozprofile == 0.28 01:52:22 INFO - mozrunner == 6.11 01:52:22 INFO - mozscreenshot == 0.1 01:52:22 INFO - mozsystemmonitor == 0.3 01:52:22 INFO - moztest == 0.7 01:52:22 INFO - mozversion == 1.4 01:52:22 INFO - psutil == 3.1.1 01:52:22 INFO - requests == 1.2.3 01:52:22 INFO - wsgiref == 0.1.2 01:52:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:22 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 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:22 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 01:52:22 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 01:52:22 INFO - Using env: {'DISPLAY': ':0', 01:52:22 INFO - 'HOME': '/home/cltbld', 01:52:22 INFO - 'LANG': 'en_US.UTF-8', 01:52:22 INFO - 'LANGUAGE': 'en_US:en', 01:52:22 INFO - 'LOGNAME': 'cltbld', 01:52:22 INFO - 'MAIL': '/var/mail/cltbld', 01:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:22 INFO - 'MOZ_NO_REMOTE': '1', 01:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:22 INFO - 'NO_EM_RESTART': '1', 01:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:22 INFO - 'PWD': '/builds/slave/test', 01:52:22 INFO - 'SHELL': '/bin/bash', 01:52:22 INFO - 'SHLVL': '1', 01:52:22 INFO - 'TERM': 'linux', 01:52:22 INFO - 'TMOUT': '86400', 01:52:22 INFO - 'USER': 'cltbld', 01:52:22 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:22 INFO - '_': '/tools/buildbot/bin/python'} 01:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 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)) 01:52:22 INFO - Cleaning up... 01:52:22 INFO - Return code: 0 01:52:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:22 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 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:22 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 01:52:22 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 01:52:22 INFO - Using env: {'DISPLAY': ':0', 01:52:22 INFO - 'HOME': '/home/cltbld', 01:52:22 INFO - 'LANG': 'en_US.UTF-8', 01:52:22 INFO - 'LANGUAGE': 'en_US:en', 01:52:22 INFO - 'LOGNAME': 'cltbld', 01:52:22 INFO - 'MAIL': '/var/mail/cltbld', 01:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:22 INFO - 'MOZ_NO_REMOTE': '1', 01:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:22 INFO - 'NO_EM_RESTART': '1', 01:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:22 INFO - 'PWD': '/builds/slave/test', 01:52:22 INFO - 'SHELL': '/bin/bash', 01:52:22 INFO - 'SHLVL': '1', 01:52:22 INFO - 'TERM': 'linux', 01:52:22 INFO - 'TMOUT': '86400', 01:52:22 INFO - 'USER': 'cltbld', 01:52:22 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:22 INFO - '_': '/tools/buildbot/bin/python'} 01:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:23 INFO - Downloading/unpacking jsonschema 01:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:52:25 INFO - Downloading/unpacking functools32 (from jsonschema) 01:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:25 INFO - Downloading functools32-3.2.3-2.tar.gz 01:52:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:52:25 INFO - warning: no files found matching '*.txt' 01:52:25 INFO - no previously-included directories found matching 'build' 01:52:25 INFO - no previously-included directories found matching 'dist' 01:52:25 INFO - no previously-included directories found matching '.git*' 01:52:25 INFO - Installing collected packages: jsonschema, functools32 01:52:25 INFO - Running setup.py install for functools32 01:52:25 INFO - warning: no files found matching '*.txt' 01:52:25 INFO - no previously-included directories found matching 'build' 01:52:25 INFO - no previously-included directories found matching 'dist' 01:52:25 INFO - no previously-included directories found matching '.git*' 01:52:25 INFO - Successfully installed jsonschema functools32 01:52:25 INFO - Cleaning up... 01:52:25 INFO - Return code: 0 01:52:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a26a7ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', '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 01:52:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 01:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:52:25 INFO - Using env: {'DISPLAY': ':0', 01:52:25 INFO - 'HOME': '/home/cltbld', 01:52:25 INFO - 'LANG': 'en_US.UTF-8', 01:52:25 INFO - 'LANGUAGE': 'en_US:en', 01:52:25 INFO - 'LOGNAME': 'cltbld', 01:52:25 INFO - 'MAIL': '/var/mail/cltbld', 01:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:25 INFO - 'MOZ_NO_REMOTE': '1', 01:52:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:25 INFO - 'NO_EM_RESTART': '1', 01:52:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:25 INFO - 'PWD': '/builds/slave/test', 01:52:25 INFO - 'SHELL': '/bin/bash', 01:52:25 INFO - 'SHLVL': '1', 01:52:25 INFO - 'TERM': 'linux', 01:52:25 INFO - 'TMOUT': '86400', 01:52:25 INFO - 'USER': 'cltbld', 01:52:25 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:25 INFO - '_': '/tools/buildbot/bin/python'} 01:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:25 INFO - Downloading/unpacking flake8 01:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:52:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:52:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:52:28 INFO - no previously-included directories found matching 'docs/_build' 01:52:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:52:28 INFO - Running setup.py install for pep8 01:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:52:28 INFO - no previously-included directories found matching 'docs/_build' 01:52:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:52:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:52:28 INFO - Cleaning up... 01:52:28 INFO - Return code: 0 01:52:28 INFO - Running post-action listener: _resource_record_post_action 01:52:28 INFO - Running post-action listener: _start_resource_monitoring 01:52:28 INFO - Starting resource monitoring. 01:52:28 INFO - ##### 01:52:28 INFO - ##### Finished create-virtualenv step (success) 01:52:28 INFO - ##### 01:52:28 INFO - ##### 01:52:28 INFO - ##### Running install step. 01:52:28 INFO - ##### 01:52:28 INFO - Running pre-action listener: _resource_record_pre_action 01:52:28 INFO - Running main action method: install 01:52:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:28 INFO - Reading from file tmpfile_stdout 01:52:28 INFO - Detecting whether we're running mozinstall >=1.0... 01:52:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:52:28 INFO - Reading from file tmpfile_stdout 01:52:28 INFO - Output received: 01:52:28 INFO - Usage: mozinstall [options] installer 01:52:28 INFO - Options: 01:52:28 INFO - -h, --help show this help message and exit 01:52:28 INFO - -d DEST, --destination=DEST 01:52:28 INFO - Directory to install application into. [default: 01:52:28 INFO - "/builds/slave/test"] 01:52:28 INFO - --app=APP Application being installed. [default: firefox] 01:52:28 INFO - mkdir: /builds/slave/test/build/application 01:52:28 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'] 01:52:28 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 01:52:45 INFO - Reading from file tmpfile_stdout 01:52:45 INFO - Output received: 01:52:45 INFO - /builds/slave/test/build/application/firefox/firefox 01:52:45 INFO - Running post-action listener: _resource_record_post_action 01:52:45 INFO - ##### 01:52:45 INFO - ##### Finished install step (success) 01:52:45 INFO - ##### 01:52:45 INFO - ##### 01:52:45 INFO - ##### Running run-tests step. 01:52:45 INFO - ##### 01:52:45 INFO - Running pre-action listener: _resource_record_pre_action 01:52:45 INFO - Running main action method: run_tests 01:52:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:52:45 INFO - Python 2.7.3 01:52:45 INFO - Return code: 0 01:52:45 INFO - grabbing minidump binary from tooltool 01:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:45 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 0x1fdf9c0>, '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 0x1fe08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe0d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:52:45 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 01:52:45 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 01:52:45 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 01:52:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:52:45 INFO - Return code: 0 01:52:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:52:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:52:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:52:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:52:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:52:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:52:46 INFO - Return code: 0 01:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 01:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 01:52:46 INFO - Using env: {'DISPLAY': ':0', 01:52:46 INFO - 'HOME': '/home/cltbld', 01:52:46 INFO - 'LANG': 'en_US.UTF-8', 01:52:46 INFO - 'LANGUAGE': 'en_US:en', 01:52:46 INFO - 'LOGNAME': 'cltbld', 01:52:46 INFO - 'MAIL': '/var/mail/cltbld', 01:52:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:52:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:46 INFO - 'MOZ_NO_REMOTE': '1', 01:52:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:52:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:52:46 INFO - 'NO_EM_RESTART': '1', 01:52:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:46 INFO - 'PWD': '/builds/slave/test', 01:52:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:52:46 INFO - 'SHELL': '/bin/bash', 01:52:46 INFO - 'SHLVL': '1', 01:52:46 INFO - 'TERM': 'linux', 01:52:46 INFO - 'TMOUT': '86400', 01:52:46 INFO - 'USER': 'cltbld', 01:52:46 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665', 01:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:46 INFO - '_': '/tools/buildbot/bin/python'} 01:52:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 01:52:46 INFO - mozversion application_buildid: 20160519233025 01:52:46 INFO - mozversion application_changeset: 00ed91d9c2c83fec8c61565ef1f73ed7a7df724a 01:52:46 INFO - mozversion application_display_name: Nightly 01:52:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:52:46 INFO - mozversion application_name: Firefox 01:52:46 INFO - mozversion application_remotingname: firefox 01:52:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:52:46 INFO - mozversion application_vendor: Mozilla 01:52:46 INFO - mozversion application_version: 49.0a1 01:52:46 INFO - mozversion platform_buildid: 20160519233025 01:52:46 INFO - mozversion platform_changeset: 00ed91d9c2c83fec8c61565ef1f73ed7a7df724a 01:52:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:52:46 INFO - mozversion platform_version: 49.0a1 01:52:46 INFO - using testdate: 1463734366 01:52:46 INFO - actual date: 1463734366 01:52:46 INFO - starting webserver on 'localhost:60860' 01:52:46 INFO - SUITE-START | Running 2 tests 01:52:46 INFO - TEST-START | dromaeo_css 01:52:46 INFO - operating with platform_type : linux_ 01:52:46 INFO - Initialising browser for dromaeo_css test... 01:52:46 INFO - TEST-INFO | started process 16726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaGBAod/profile http://localhost:60860/getInfo.html) 01:52:51 INFO - PROCESS | 16726 | __metrics Screen width/height:1600/1200 01:52:51 INFO - PROCESS | 16726 | colorDepth:24 01:52:51 INFO - PROCESS | 16726 | Browser inner width/height: 1024/697 01:52:51 INFO - PROCESS | 16726 | __metrics 01:52:52 ERROR - PROCESS | 16726 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:52:53 INFO - TEST-INFO | 16726: exit 0 01:52:53 INFO - Browser initialized. 01:52:53 INFO - Running cycle 1/1 for dromaeo_css test... 01:52:53 INFO - TEST-INFO | started process 16808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaGBAod/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:53:05 INFO - PROCESS | 16808 | RSS: Main: 152567808 01:53:05 INFO - PROCESS | 16808 | 01:53:55 INFO - PROCESS | 16808 | 1463734435818 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] 01:56:03 INFO - PROCESS | 16808 | Cycle 1(1): loaded http://localhost:60860/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60860/tests/dromaeo/cssquery-ext.html) 01:56:04 INFO - PROCESS | 16808 | RSS: Main: 259686400 01:56:04 INFO - PROCESS | 16808 | 01:59:06 INFO - PROCESS | 16808 | Cycle 1(1): loaded http://localhost:60860/tests/dromaeo/cssquery-ext.html (next: http://localhost:60860/tests/dromaeo/cssquery-jquery.html) 01:59:07 INFO - PROCESS | 16808 | RSS: Main: 234975232 01:59:07 INFO - PROCESS | 16808 | 02:02:09 INFO - PROCESS | 16808 | Cycle 1(1): loaded http://localhost:60860/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60860/tests/dromaeo/cssquery-mootools.html) 02:02:10 INFO - PROCESS | 16808 | RSS: Main: 237273088 02:02:10 INFO - PROCESS | 16808 | 02:05:12 INFO - PROCESS | 16808 | Cycle 1(1): loaded http://localhost:60860/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60860/tests/dromaeo/cssquery-prototype.html) 02:05:12 INFO - PROCESS | 16808 | RSS: Main: 231731200 02:05:12 INFO - PROCESS | 16808 | 02:08:15 INFO - PROCESS | 16808 | Cycle 1(1): loaded http://localhost:60860/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60860/tests/dromaeo/cssquery-yui.html) 02:08:15 INFO - PROCESS | 16808 | RSS: Main: 217497600 02:08:15 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | Cycle 1(1): loaded http://localhost:60860/tests/dromaeo/cssquery-yui.html (next: http://localhost:60860/tests/dromaeo/cssquery-dojo.html) 02:11:18 INFO - PROCESS | 16808 | RSS: Main: 269647872 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | __start_tp_report 02:11:18 INFO - PROCESS | 16808 | _x_x_mozilla_page_load 02:11:18 INFO - PROCESS | 16808 | _x_x_mozilla_page_load_details 02:11:18 INFO - PROCESS | 16808 | |i|pagename|runs| 02:11:18 INFO - PROCESS | 16808 | |0;dojo.html;3051.8626661800217;3062.035458830468;3086.66046734859;3094.628644562652;3118.672539383365;5159.148740457829;5186.09243382408;5273.709945952971;5283.233931079991;5320.308359913211;5255.894882102356;5294.735263236842;5312.415634280226;5323.361196656397;5336;4716.221823399135;4727.432708075019;4881.243407271879;4893.2170852663585;4915.139850526161;4855.053264613402;4878.48775878533;4901.068796928572;4916.360873086512;4922.08694088199;4715.834945776898;4739.73931433771;4790.377250957354;4820.204666230085;4864.075825593126;4835;4847.733374664392;4848.806047758086;4862.756862156878;4887.218045112801;4673.556012178838;4734.597559207472;4825.493323201048;4851.078295123951;4871.537203965618;5221.765020574046;5309.548688361494;5325.254464374991;5327.121025030865;5327.706976116312;5611;5698.173764804122;5700.315962084576;5700.543956483471;5704.629199102086;5512.145617429305;5561.026820306471;5588.385277619459;5672.602917795756;5721.34204566476;5337.2527846101575;5348.411674715778;5362.249285100128;5362.49056339651;5363.9463605363835;5894.233749612608;5900.4984576310635;5906;5907.32065812437;5911.467967882825;5304.973475132677;5415.593830462731;5498.917516237259;5512.118061110203;5656.660600363992;5658.434156584309;5662.065759149777;5709.115087161496;5714.514266287329;5715.771369145187;5612.719364031782;5618.6628802272;5620.57845661577;5625.662460252398;5644.040513112781;5498.80754173602;5571.9442805572235;5572.944270557243;5619.988402087663;5692.800751973661;4346.152500262482;4349;4349.956500435019;4350.347447882779;4384.0355121873135;3745.4756334113245;3886.358750806142;3896.5518965318784;3939.1333906540517;3945.349017412152;5296.3379577552805;5349.705766182819;5353.812616558402;5384;5391.433899440581;5690.430956904276;5718.685472299064;5728.856778580569;5734.05388110957;5735.4551317624755;5161.3032240647035;5312.043832110257;5380.2736630555155;5395.001924643953;5426.647267927572;4685.99251161002;4707.811687532529;4727.054589082198;4728.267118596623;4769.379980602569;5962.25471816023;5972.731227094791;5975.232660621786;5978;6027.517798576104;5505.697186654612;5555.30558680165;5603.887922241451;5768.942310576925;5775.249217601685;5857.5313974881055;5891.941080589139;5905.43898329658;5909.320428150818;5929.169916211647;5575.386707462095;5588.944110558842;5603.55171586264;5604.271444712324;5605.075162598126;5553.694546799803;5611.130274807411;5634.971825140848;5667.659940403589;5706.657600543981;5573.526250268773;5575.832725018256;5579.525740311957;5580.86047848807;5589.189567512769;5509.256250406304;5571.693556854412;5575.1915972184615;5576.832695019156;5577.330720313588;5343.198520221998;5360.410354860885;5370.167624018283;5409.567234621142;5455.454454554513;5549.278593782782;5565.1930470082425;5577.3028371453565;5608.7195640217005;5623.493885550321;5314.760835762323;5447.155690867922;5450.400455949763;5454.018276710231;5493.972530137324 02:11:18 INFO - PROCESS | 16808 | |1;ext.html;592.834006478186;619.516776914007;627.6296984778983;634.1059106659611;639.9200099987502;30631.008984416025;31117.844410777943;33047.83476082616;33171.66828331716;42449.15101697964;40882.18235635285;43883.341749873776;43983;43990.12019759611;44071.338929916;8574.614142363593;8805.42764720291;8808.955955220215;8809.471431714086;8817.338699597556;8510.957445212796;8656.653733850639;8670.52312122833;8673.783155421102;8677.609507572175;8607.655693772242;8681;8697.217250447458;8699.304055675562;8701.825963480727;8583.871241931345;8633.309335253227;8650.43772154811;8650.437721548142;8650.697225597138;8546.658133674708;8638.222559969632;8651.65393384264;8667.479951202886;8673.176048275403;39071.609283907084;39544.209115817524;39570.80214598937;39632.405513917314;39643.20713585712;3366.797992120456;3385.238321377695;3391.9830400847836;3582.982085089558;3668.211334563055;31451;32025.839870800497;32161.517577236362;32357.51463728046;32850.83574582136;4121.464209652756;4171.770552619605;4227.112306415656;4245.193413251503;4248.745075295492;325765;329656;330184.9999999976;330227;330919.3454032738;52026.47973520292;55073.1739023915;56721.14918276229;57290.42709572934;57454;53540;59168.40831591715;59266;59515;59567;15280.847191528164;15317.846821531642;15344.079355238722;15380;15386.230688465532;11033.337999720043;11882.643520694393;11985.580504682293;12677.809832852514;12739.872601274054;16316.34734610626;16324.591885202964;16325.755113673304;16345.34618615266;16349.673006539802;6116.3577824328695;6123.234595675541;6207.503399728011;6209.130721698965;6276.340984196685;974.2303580171726;1070.4487189097622;1105.6848798092665;1115.4590023838432;1145.3242586873785;32087.679123208934;32094.35811283808;32157.35685286328;32456.188595285308;33000;1100.8203756562052;1113.6547670222258;1128.3455595754606;1179.4161889864572;1204.476052916984;16396.508104756875;16985.40551080706;17033.829661703472;17061.57346066358;17148.142592870307;484058;485723;491958;493483;493566;55810;56126.43873561294;56137.71931140318;56206.71896640491;56267;1274.133589159362;1281.2952875918209;1338.2305174524492;1370.6230786533583;1396.9720605588034;31530.36939261156;32578;32931.176720582174;33006;33172.83413583013;47760.52239477561;48131.75934120307;48427.5157248423;48508.5149148504;48720.512794871596;317477;317778;318537;318621;319863;35045;39666.206675865746;40292;40347;43394;46660;47153.292700608836;47294.290585640556;47594;47600;40447;40583.79708101559;40744.59255407408;41506.58493415027;41952.790236048626;39192.21615567616;40301.596984029784;41158.588414115475;41195.7940210297;41826.79086604548;40190.598094018686;43194.56805432031;43333.34999975066;43492;43799.56200438082;43609;43687.78156109199;43932.12135757203;44695.55304446914;44716;33529.49705754464;34623.82688086543;34830;34944;35351.823240883634 02:11:18 INFO - PROCESS | 16808 | |2;jquery.html;395.984300269808;399.1179493319763;402.63762613647714;404.2077528045032;419.25163583004326;13112.642943780855;18838.43484695458;19465.70801437978;19525;20862.269820556245;14388.388528473904;18271.08644567773;18465;19242.807571924277;19782.70325945112;5586.078297080987;5746.051901436258;5779.017567013597;5786.334571524266;5798.7100644967795;5667.206591077253;5756.8848623027725;5764.394738552454;5770.740316685759;5774.6535207887455;4141.358089496118;6038.396160383948;6352.110704501373;6356.936430635704;6495.6752162391695;6374.840628984266;6428.614283143003;6445.968645016822;6449.322821103787;6461.741530338781;5543.002259593271;5650.943490565083;5672.574556908248;5682.857928551778;5691.459311365414;21956.267434536167;22595.77404225953;23407.531849363088;23952.640710389183;23983.160589379288;2450.6814114165045;2455.3861534616344;2543.2878793937725;2644.5504264274928;3036.635603727567;14030.298485075706;14288.785668214985;14417.207053612046;14537;14729.410823567045;1925.894075825829;1927;1987.224982256921;2005.0977060322914;2058.279602139254;75674.24325756782;76918.6154069226;77484.61257693732;77795;77964.61017694933;10431.634892778868;10459.84310235347;10938.179636527293;11014.779704405866;11159.609413670483;15024.17367044823;15041.623959400884;15084.094954302776;15112.168830714416;15136.470223542123;7979.401544884041;8079;8250.46371985831;8298.253157215879;8320.167983201753;7782.338501227346;7864.72473463426;7932.206779322021;7944;8008.27925486709;6036.667983260875;6328.430441260309;6372.2671892732005;6703;6879.626449226608;1872.0265461959705;1930.2182616040527;1934.6711059119984;1962.8681625179079;1994.7706013808308;926.3191554207652;989.5250279865561;1034.2036631793628;1087.597588892099;1123.1239633086207;19175.808241917402;19872;19874.60250794976;19902.60194796096;20085.59828803445;1026.830572955454;1034.715453250347;1043.796459690353;1063.9734006649896;1077.7575045614674;10923.836142457869;11406.942965285287;11483.368414737102;11516.712082198012;11524.481398337095;415491;422192;422883;423539;424206;83968.58015709883;84047;85353;85572.57213713892;85643.14356856352;1034.0590063042746;1043.6451606453843;1048.4757621189558;1055.3351388625117;1071.4482041748506;17510;17632.206550705505;17733.20200591002;17849.37527186542;18063.638727225643;30712.53931190989;37223.62776372202;38497.23005539818;39252.80373598228;40455.59544404518;52097;53126;53242.20136697875;53744;53806.730966346484;7231.204567497466;7605.809854753676;8787.956060219914;8893.688720894737;9054.63781448761;11045.33727976324;13236.404361803934;13350.732985340434;13484.190948542726;13976.091554048955;12097.455614497545;12119.697007574881;12242.938785306016;12276.938615307223;12311.938440307742;6672.966635166793;9095.454272743656;11711.531538738354;11910.404479776149;11933.105017123924;12174.686809506165;12220.938895305466;12224.266544007389;12286.631401057983;12382;8528.616212270339;8721.781955451164;8773.780655483664;8903.065178156201;8948.73153805387;11494.942525287655;11973.281603103842;12006.339651318913;12415.93792031034;12437.875621243671 02:11:18 INFO - PROCESS | 16808 | |3;mootools.html;1350.7366063617587;1465.6702241995552;1504.97742533862;1666.5916850371661;1835.2658936425428;8000.639971201295;8295.046069701979;8311.501309921408;8552.444091134072;8567.91432085679;8343.83312333753;8515.105913879046;8527.104654011317;8538.231559159674;8542.871856922151;2003.2187446895723;2237.899294531742;2277.840551161415;2291.232437133562;2315.513742114158;2370.8814559272055;2375.0974629640714;2375.3111597636666;2378.702662167229;2383.9642405363934;2294.552562250362;2312.28319221042;2347.4835536182013;2347.730011048726;2350.095213342867;1950.5318723506398;2199.1313431194667;2215.8559693619864;2264.388615073921;2264.4452109233343;2253.5943530164564;2273.1589311954517;2294.5181511882524;2298.0807676929194;2298.390926404504;8402.705905293285;8518.829623407497;8532.104129066483;8536.189062039097;8557.486550806909;5248.422673505911;5372.221027950925;5391.595630327741;5397.055515284848;5397.163439666858;3176.745860331168;3183.1405520509566;3230.951535726966;3301.570795796531;3341.1480072581335;1032.7314898126467;1035.5081336365242;1037.1080870451406;1038.6883934819523;1045.3362115056993;11521.48153333102;11659.591914282959;11665.591704290395;11666.125040621988;11670.649880503599;4642.65180111493;4717.905641887143;4754.643401744883;4755.262934245128;4796.616270698336;9894.752631184134;9903.25725570585;9909.75225619351;9919.355241909252;9921.801563968824;5148.6910785353;5198.324217851655;5206.583473322125;5210.76551555181;5213.687178769286;4109.0959988802415;4573.314002899591;4654.115718013567;4660.277656963176;4748.097861406391;4049.6152865477734;4058.857939972087;4073.190765059249;4084.611961863618;4124.587541245912;1258.1004581724112;1271.4850485553366;1272.3256673962815;1286.2411177405377;1309.3780454284138;964.8263312603658;973.6689525561202;977.672479719302;980.8136454073846;990.5889056041806;5637.633553818989;5792.9710351447975;5844.795432159854;5851.561132915049;5978.342382337939;399.68025579536254;400.6514332530717;409.81763115413355;410.9383592461095;410.9773962432035;4166.520850102277;4180.184863951561;4181.937270940938;4184.539700632928;4197;10043.296969212231;10360.948195259127;10409.947950260352;10438.478076096164;10459.529321180566;10088.596456141819;10182.083612474913;10182.796344073226;10207.438590877424;10213.131883789962;7809.3752499799875;7880.566568838654;7909.169537198626;7945.245201705829;8123.350131989427;5886.587938844411;5952.315483719428;5983.850403739942;6081.027035674271;6121.602095863755;9456.621735130519;9734.318597698093;9787.412755234709;9875.506224688881;9942.254330925065;9650.565724542554;9661.082197191256;9709.223262139154;9813.509324533887;9817.901820981699;6740.764073257413;7162.283771622796;7293.197748247795;7336.229695882069;7520.736774212876;8360.205780450848;8379.287760540179;8412.074671785977;8478.02502712196;8481.15188481147;7114.608696521741;7295.671694773641;7319.304666056716;7322.231165727735;7417.740379086706;7152.570845749272;7165;7181.06646135999;7207.459440541876;7235.604907316208;6335.113084168128;6381.361863813767;6391.808245752635;6401.391867772554;6402.583832050902;4885.145099607484;4982.177940639754;5034.496550344937;5063.746812659426;5083.974580127076;4929.975350123226;5064.037832811754;5091.541761241358;5160.793568257228;5163.561097306771 02:11:18 INFO - PROCESS | 16808 | |4;prototype.html;313.7881929697454;320.9309998350355;322.17487930871613;330.08566271428145;330.2256209189453;5296.894062118756;5298.735063246837;5308.495692909177;5317.8936421271555;5322.308099947007;5254.264402983586;5260.237265596486;5263.31576895004;5290.603204759639;5337.332833395825;3996.880873355458;4037.2934736421157;4123.649489793356;4142.5028996520305;4190.769507677077;4091.304478238692;4093.672506199497;4105.261053010457;4120.464339635858;4170.416141740159;4079.6124368184974;4083.183363327332;4084.775337356444;4093.2836753568154;4134.937975930348;3935.756618727341;3944.4872166618297;4136.131412403399;4151.232022075926;4162.687798415101;3687.1888184599347;3691.2617476504806;3698.8335524901445;3701.3522633539023;3703.5000274962804;5116.079105760961;5135.12702840518;5168.415792103948;5171.095058364771;5183.922241166386;4552.314134660566;4786.138495070886;4788.61691064714;4797.640176986705;4820.783064762094;5504.614676972614;5510.421405752419;5512.558995280408;5531.3362396512275;5551.278333816618;4094.2016306820183;4107.568705285932;4110.095778928631;4110.23960567293;4119.629233368982;5556.77772889088;5832.562557808139;5843.53251739864;5878.823635290948;5887.322957859858;4041.7170798043853;4046.1705350403095;4075.1849630074103;4076.144009757925;4086.652634526039;4128.339465685476;4135.338345864708;4136.21411931732;4138.489451350236;4139;4574.5882870541145;4740.976295118571;4761.381020467317;4796.952030479721;4836.830710925101;5439.891202175934;5571.9721401392735;5593.692346920877;5627.396192085318;5639.802606908738;1438.4461982136952;1439.4673970630934;1445.2123592642035;1452.7385070687167;1460.6421426750514;907.1155623267261;915.6200176926634;936.8922573904089;939.9624015039459;966.6665000574791;5206.583473322125;5417.458254174551;5437.13005919051;5457.945420545823;5489.890202195934;5787.614245666248;5791.131330300489;5819;5821.854453638608;5834.5121993892135;5276;5361.249425080492;5396.757145928443;5476.878179437762;5483.533899618497;3006.353633968707;3010.5097976501334;3025.561293612414;3042.437149127374;3052.404781067715;5760.971195144081;5904.261967254093;5906.143609176645;5920.31916329619;5947.167396564484;5745.6552606843725;5825.941740582624;5827.854303642358;5829.300483941955;5846.970765146232;5858.2677165354335;5890.175375447441;5899.410058994066;5932.525397968152;5985.670788106609;5435.266239057834;5750.281214848144;5750.913736294043;5760.798372056958;5781.8554536136935;5598.244237027949;5654.745536450787;5677.148427735872;5686.147077938343;5695.715214239354;5946.256717910262;5950.155451809953;5962.19510366095;5978.372270911492;5997.640141591519;3814.1990182062077;4053.6554392876938;4058.7158898877974;4060.9187816244103;4066.796660167039;5659.858503537444;5659.943400565941;5665.603407761582;5694.886102278014;5738.598298119092;5323.973380133075;5545.445455454423;5554.222408862844;5555.194496798023;5588.972055139699;5494.2582751328055;5499.835004949858;5507.834764957058;5522.834314970557;5538.889222215614;5253.579713622978;5387.461253874581;5462.9180562292395;5481.616286859881;5492.697901615449;5420.647657902224;5615.775368985358;5622.7469763861545;5630.634008789416;5635.182898479616;5461.481159289861;5499.285092937893;5503.642263252876;5511.4016935088375;5523.6409633373705 02:11:18 INFO - PROCESS | 16808 | |5;yui.html;796.8485987662343;802.1216767639435;818.1654712193563;820.9384296177789;834.9248567628912;14654.926725366371;14933.92533037336;15126.394944202219;15140.318685659144;15229.619259518518;13910.652233694163;13963.720725585483;14080.929595352009;14138.363773630186;14138.787918181235;675.665545554951;787.3307688464816;789.4355535791908;798.3573223555036;806.8265322955549;811.4806523824757;817.6034623207739;818.2431251092745;819.2176471469733;825.8059356051353;846.9915300847005;861.1861790607878;862.1981557151846;862.2326129744532;862.9482231066124;706.4101475268131;826.7147833997261;828.825946551225;837.9455335403179;855.7389996051202;591.9704014799242;595.6068994463633;595.7795615622205;598.6946657204857;598.9431004054608;14531.346089426;14583.343749531297;14639.48761793332;14644.414223431157;14754.336054877556;13993.860061399357;14321.856781432156;14343.641408964755;14386.712265754626;14430;11145.832812507819;11175.385353805537;11250.324980501196;11366.37484938328;11610.36143012134;3805.124821291091;3835.42468629705;3878.2437424702193;3898.590647981978;3934.1148241645687;58735.70632146855;59019.7760990444;59386.70306648482;59487.10769338506;59541.40458595445;11401.828972565418;11493.77012459746;11601.883981160081;11650.766984660258;11731.589394371156;20709.792902071087;20901.79098209029;20923.476913077295;21346.78653213479;21402.892985534974;7926.722564710323;7952.681892724342;7964.920350796534;8110.594470276463;8324.583770811556;4698.8355407560575;4699.130660827758;4810.542998415145;4825.348577942003;4826.927596086061;3536.2750636119536;3621.167131559756;3631.7457777955287;3634.6547078027547;3639.6178401267484;667.366002297819;674.5042393840523;695.8712638161957;699.136566340569;710.3961632612238;708.2527933030702;711.6868577825741;739.1721272175091;751.7331347371713;761.3604572159413;8775.990761062432;9014.819703606023;9223.76940576491;9269.768255793659;9338;561.5703986450369;600.7236671131241;621.2141640824361;635.1076737184263;665.9334066593399;4498.527654596286;4588.449386073626;4595.6783025187915;4606.493285738566;4732.503087175865;71966.28033719701;72008.63995679987;72639.2736072643;78543.60728196322;79538.20461795424;66921.6653916737;67262.3273767256;68102.31897680959;68814.31185688179;71337.64331178415;63092;63394.683026585495;63465;63925.36074639194;64146.679266603365;8687.522186279815;8850.557472126497;8863.784053986503;8905.55472226399;8911.331650126136;39605.80197099112;40770.79614601908;40879;41347.793261033505;41761.373579395724;55365.16952245639;55591.72204139115;55782.72108639431;55872.72063639656;56686;12376.009919206623;12577.87422125767;12612.432440540015;12892.935535322264;13080.803787942998;12678.366081695694;12735.426905789447;12883.677908052374;13017.349132543524;13424.328783560979;10777.461126943464;10852;11430.085593152695;11536.769264614828;11688.88311116912;11731.23746956445;11778.293302401884;11796.174267801172;11838.112141589243;11938.52245910154;10907.181961352771;11497.691486921443;11531.884681153417;11612.883871161519;11716.531338746696;9719.368241064309;9914.454704991007;9989.250806189419;10000.499975001367;10425.843612345669;10186.236032297458;10320.380777153094;10360;10637.680869573926;10651.94674026656 02:11:18 INFO - PROCESS | 16808 | __end_tp_report 02:11:18 INFO - PROCESS | 16808 | __start_cc_report 02:11:18 INFO - PROCESS | 16808 | _x_x_mozilla_cycle_collect,922 02:11:18 INFO - PROCESS | 16808 | __end_cc_report 02:11:18 INFO - PROCESS | 16808 | __startTimestamp1463735478488__endTimestamp 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | ------- Summary: start ------- 02:11:18 INFO - PROCESS | 16808 | Number of tests: 6 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | [#0] dojo.html Cycles:165 Average:5263.67 Median:5447.16 stddev:601.52 (11.0%) stddev-sans-first:577.80 02:11:18 INFO - PROCESS | 16808 | Values: 3051.9 3062.0 3086.7 3094.6 3118.7 5159.1 5186.1 5273.7 5283.2 5320.3 5255.9 5294.7 5312.4 5323.4 5336.0 4716.2 4727.4 4881.2 4893.2 4915.1 4855.1 4878.5 4901.1 4916.4 4922.1 4715.8 4739.7 4790.4 4820.2 4864.1 4835.0 4847.7 4848.8 4862.8 4887.2 4673.6 4734.6 4825.5 4851.1 4871.5 5221.8 5309.5 5325.3 5327.1 5327.7 5611.0 5698.2 5700.3 5700.5 5704.6 5512.1 5561.0 5588.4 5672.6 5721.3 5337.3 5348.4 5362.2 5362.5 5363.9 5894.2 5900.5 5906.0 5907.3 5911.5 5305.0 5415.6 5498.9 5512.1 5656.7 5658.4 5662.1 5709.1 5714.5 5715.8 5612.7 5618.7 5620.6 5625.7 5644.0 5498.8 5571.9 5572.9 5620.0 5692.8 4346.2 4349.0 4350.0 4350.3 4384.0 3745.5 3886.4 3896.6 3939.1 3945.3 5296.3 5349.7 5353.8 5384.0 5391.4 5690.4 5718.7 5728.9 5734.1 5735.5 5161.3 5312.0 5380.3 5395.0 5426.6 4686.0 4707.8 4727.1 4728.3 4769.4 5962.3 5972.7 5975.2 5978.0 6027.5 5505.7 5555.3 5603.9 5768.9 5775.2 5857.5 5891.9 5905.4 5909.3 5929.2 5575.4 5588.9 5603.6 5604.3 5605.1 5553.7 5611.1 5635.0 5667.7 5706.7 5573.5 5575.8 5579.5 5580.9 5589.2 5509.3 5571.7 5575.2 5576.8 5577.3 5343.2 5360.4 5370.2 5409.6 5455.5 5549.3 5565.2 5577.3 5608.7 5623.5 5314.8 5447.2 5450.4 5454.0 5494.0 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | [#1] ext.html Cycles:180 Average:55068.22 Median:22658.63 stddev:102194.42 (451.0%) stddev-sans-first:102399.25 02:11:18 INFO - PROCESS | 16808 | Values: 592.8 619.5 627.6 634.1 639.9 30631.0 31117.8 33047.8 33171.7 42449.2 40882.2 43883.3 43983.0 43990.1 44071.3 8574.6 8805.4 8809.0 8809.5 8817.3 8511.0 8656.7 8670.5 8673.8 8677.6 8607.7 8681.0 8697.2 8699.3 8701.8 8583.9 8633.3 8650.4 8650.4 8650.7 8546.7 8638.2 8651.7 8667.5 8673.2 39071.6 39544.2 39570.8 39632.4 39643.2 3366.8 3385.2 3392.0 3583.0 3668.2 31451.0 32025.8 32161.5 32357.5 32850.8 4121.5 4171.8 4227.1 4245.2 4248.7 325765.0 329656.0 330185.0 330227.0 330919.3 52026.5 55073.2 56721.1 57290.4 57454.0 53540.0 59168.4 59266.0 59515.0 59567.0 15280.8 15317.8 15344.1 15380.0 15386.2 11033.3 11882.6 11985.6 12677.8 12739.9 16316.3 16324.6 16325.8 16345.3 16349.7 6116.4 6123.2 6207.5 6209.1 6276.3 974.2 1070.4 1105.7 1115.5 1145.3 32087.7 32094.4 32157.4 32456.2 33000.0 1100.8 1113.7 1128.3 1179.4 1204.5 16396.5 16985.4 17033.8 17061.6 17148.1 484058.0 485723.0 491958.0 493483.0 493566.0 55810.0 56126.4 56137.7 56206.7 56267.0 1274.1 1281.3 1338.2 1370.6 1397.0 31530.4 32578.0 32931.2 33006.0 33172.8 47760.5 48131.8 48427.5 48508.5 48720.5 317477.0 317778.0 318537.0 318621.0 319863.0 35045.0 39666.2 40292.0 40347.0 43394.0 46660.0 47153.3 47294.3 47594.0 47600.0 40447.0 40583.8 40744.6 41506.6 41952.8 39192.2 40301.6 41158.6 41195.8 41826.8 40190.6 43194.6 43333.3 43492.0 43799.6 43609.0 43687.8 43932.1 44695.6 44716.0 33529.5 34623.8 34830.0 34944.0 35351.8 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | [#2] jquery.html Cycles:180 Average:26720.20 Median:13216.92 stddev:69583.05 (526.5%) stddev-sans-first:69750.17 02:11:18 INFO - PROCESS | 16808 | Values: 396.0 399.1 402.6 404.2 419.3 13112.6 18838.4 19465.7 19525.0 20862.3 14388.4 18271.1 18465.0 19242.8 19782.7 5586.1 5746.1 5779.0 5786.3 5798.7 5667.2 5756.9 5764.4 5770.7 5774.7 4141.4 6038.4 6352.1 6356.9 6495.7 6374.8 6428.6 6446.0 6449.3 6461.7 5543.0 5650.9 5672.6 5682.9 5691.5 21956.3 22595.8 23407.5 23952.6 23983.2 2450.7 2455.4 2543.3 2644.6 3036.6 14030.3 14288.8 14417.2 14537.0 14729.4 1925.9 1927.0 1987.2 2005.1 2058.3 75674.2 76918.6 77484.6 77795.0 77964.6 10431.6 10459.8 10938.2 11014.8 11159.6 15024.2 15041.6 15084.1 15112.2 15136.5 7979.4 8079.0 8250.5 8298.3 8320.2 7782.3 7864.7 7932.2 7944.0 8008.3 6036.7 6328.4 6372.3 6703.0 6879.6 1872.0 1930.2 1934.7 1962.9 1994.8 926.3 989.5 1034.2 1087.6 1123.1 19175.8 19872.0 19874.6 19902.6 20085.6 1026.8 1034.7 1043.8 1064.0 1077.8 10923.8 11406.9 11483.4 11516.7 11524.5 415491.0 422192.0 422883.0 423539.0 424206.0 83968.6 84047.0 85353.0 85572.6 85643.1 1034.1 1043.6 1048.5 1055.3 1071.4 17510.0 17632.2 17733.2 17849.4 18063.6 30712.5 37223.6 38497.2 39252.8 40455.6 52097.0 53126.0 53242.2 53744.0 53806.7 7231.2 7605.8 8788.0 8893.7 9054.6 11045.3 13236.4 13350.7 13484.2 13976.1 12097.5 12119.7 12242.9 12276.9 12311.9 6673.0 9095.5 11711.5 11910.4 11933.1 12174.7 12220.9 12224.3 12286.6 12382.0 8528.6 8721.8 8773.8 8903.1 8948.7 11494.9 11973.3 12006.3 12415.9 12437.9 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | [#3] mootools.html Cycles:180 Average:5590.50 Median:5074.01 stddev:3133.79 (61.8%) stddev-sans-first:3126.39 02:11:18 INFO - PROCESS | 16808 | Values: 1350.7 1465.7 1505.0 1666.6 1835.3 8000.6 8295.0 8311.5 8552.4 8567.9 8343.8 8515.1 8527.1 8538.2 8542.9 2003.2 2237.9 2277.8 2291.2 2315.5 2370.9 2375.1 2375.3 2378.7 2384.0 2294.6 2312.3 2347.5 2347.7 2350.1 1950.5 2199.1 2215.9 2264.4 2264.4 2253.6 2273.2 2294.5 2298.1 2298.4 8402.7 8518.8 8532.1 8536.2 8557.5 5248.4 5372.2 5391.6 5397.1 5397.2 3176.7 3183.1 3231.0 3301.6 3341.1 1032.7 1035.5 1037.1 1038.7 1045.3 11521.5 11659.6 11665.6 11666.1 11670.6 4642.7 4717.9 4754.6 4755.3 4796.6 9894.8 9903.3 9909.8 9919.4 9921.8 5148.7 5198.3 5206.6 5210.8 5213.7 4109.1 4573.3 4654.1 4660.3 4748.1 4049.6 4058.9 4073.2 4084.6 4124.6 1258.1 1271.5 1272.3 1286.2 1309.4 964.8 973.7 977.7 980.8 990.6 5637.6 5793.0 5844.8 5851.6 5978.3 399.7 400.7 409.8 410.9 411.0 4166.5 4180.2 4181.9 4184.5 4197.0 10043.3 10360.9 10409.9 10438.5 10459.5 10088.6 10182.1 10182.8 10207.4 10213.1 7809.4 7880.6 7909.2 7945.2 8123.4 5886.6 5952.3 5983.9 6081.0 6121.6 9456.6 9734.3 9787.4 9875.5 9942.3 9650.6 9661.1 9709.2 9813.5 9817.9 6740.8 7162.3 7293.2 7336.2 7520.7 8360.2 8379.3 8412.1 8478.0 8481.2 7114.6 7295.7 7319.3 7322.2 7417.7 7152.6 7165.0 7181.1 7207.5 7235.6 6335.1 6381.4 6391.8 6401.4 6402.6 4885.1 4982.2 5034.5 5063.7 5084.0 4930.0 5064.0 5091.5 5160.8 5163.6 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | [#4] prototype.html Cycles:180 Average:4694.98 Median:5392.11 stddev:1383.65 (25.7%) stddev-sans-first:1347.89 02:11:18 INFO - PROCESS | 16808 | Values: 313.8 320.9 322.2 330.1 330.2 5296.9 5298.7 5308.5 5317.9 5322.3 5254.3 5260.2 5263.3 5290.6 5337.3 3996.9 4037.3 4123.6 4142.5 4190.8 4091.3 4093.7 4105.3 4120.5 4170.4 4079.6 4083.2 4084.8 4093.3 4134.9 3935.8 3944.5 4136.1 4151.2 4162.7 3687.2 3691.3 3698.8 3701.4 3703.5 5116.1 5135.1 5168.4 5171.1 5183.9 4552.3 4786.1 4788.6 4797.6 4820.8 5504.6 5510.4 5512.6 5531.3 5551.3 4094.2 4107.6 4110.1 4110.2 4119.6 5556.8 5832.6 5843.5 5878.8 5887.3 4041.7 4046.2 4075.2 4076.1 4086.7 4128.3 4135.3 4136.2 4138.5 4139.0 4574.6 4741.0 4761.4 4797.0 4836.8 5439.9 5572.0 5593.7 5627.4 5639.8 1438.4 1439.5 1445.2 1452.7 1460.6 907.1 915.6 936.9 940.0 966.7 5206.6 5417.5 5437.1 5457.9 5489.9 5787.6 5791.1 5819.0 5821.9 5834.5 5276.0 5361.2 5396.8 5476.9 5483.5 3006.4 3010.5 3025.6 3042.4 3052.4 5761.0 5904.3 5906.1 5920.3 5947.2 5745.7 5825.9 5827.9 5829.3 5847.0 5858.3 5890.2 5899.4 5932.5 5985.7 5435.3 5750.3 5750.9 5760.8 5781.9 5598.2 5654.7 5677.1 5686.1 5695.7 5946.3 5950.2 5962.2 5978.4 5997.6 3814.2 4053.7 4058.7 4060.9 4066.8 5659.9 5659.9 5665.6 5694.9 5738.6 5324.0 5545.4 5554.2 5555.2 5589.0 5494.3 5499.8 5507.8 5522.8 5538.9 5253.6 5387.5 5462.9 5481.6 5492.7 5420.6 5615.8 5622.7 5630.6 5635.2 5461.5 5499.3 5503.6 5511.4 5523.6 02:11:18 INFO - PROCESS | 16808 | 02:11:18 INFO - PROCESS | 16808 | [#5] yui.html Cycles:180 Average:16531.93 Median:4826.14 stddev:20883.96 (432.7%) stddev-sans-first:20909.12 02:11:18 INFO - PROCESS | 16808 | Values: 796.8 802.1 818.2 820.9 834.9 14654.9 14933.9 15126.4 15140.3 15229.6 13910.7 13963.7 14080.9 14138.4 14138.8 675.7 787.3 789.4 798.4 806.8 811.5 817.6 818.2 819.2 825.8 847.0 861.2 862.2 862.2 862.9 706.4 826.7 828.8 837.9 855.7 592.0 595.6 595.8 598.7 598.9 14531.3 14583.3 14639.5 14644.4 14754.3 13993.9 14321.9 14343.6 14386.7 14430.0 11145.8 11175.4 11250.3 11366.4 11610.4 3805.1 3835.4 3878.2 3898.6 3934.1 58735.7 59019.8 59386.7 59487.1 59541.4 11401.8 11493.8 11601.9 11650.8 11731.6 20709.8 20901.8 20923.5 21346.8 21402.9 7926.7 7952.7 7964.9 8110.6 8324.6 4698.8 4699.1 4810.5 4825.3 4826.9 3536.3 3621.2 3631.7 3634.7 3639.6 667.4 674.5 695.9 699.1 710.4 708.3 711.7 739.2 751.7 761.4 8776.0 9014.8 9223.8 9269.8 9338.0 561.6 600.7 621.2 635.1 665.9 4498.5 4588.4 4595.7 4606.5 4732.5 71966.3 72008.6 72639.3 78543.6 79538.2 66921.7 67262.3 68102.3 68814.3 71337.6 63092.0 63394.7 63465.0 63925.4 64146.7 8687.5 8850.6 8863.8 8905.6 8911.3 39605.8 40770.8 40879.0 41347.8 41761.4 55365.2 55591.7 55782.7 55872.7 56686.0 12376.0 12577.9 12612.4 12892.9 13080.8 12678.4 12735.4 12883.7 13017.3 13424.3 10777.5 10852.0 11430.1 11536.8 11688.9 11731.2 11778.3 11796.2 11838.1 11938.5 10907.2 11497.7 11531.9 11612.9 11716.5 9719.4 9914.5 9989.3 10000.5 10425.8 10186.2 10320.4 10360.0 10637.7 10651.9 02:11:18 INFO - PROCESS | 16808 | -------- Summary: end -------- 02:11:18 INFO - PROCESS | 16808 | 02:11:19 INFO - TEST-INFO | 16808: exit 0 02:11:19 INFO - TEST-OK | dromaeo_css | took 1112421ms 02:11:19 INFO - TEST-START | kraken 02:11:19 INFO - operating with platform_type : linux_ 02:11:19 INFO - Initialising browser for kraken test... 02:11:19 INFO - TEST-INFO | started process 16992 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDRBwvP/profile http://localhost:60860/getInfo.html) 02:11:23 INFO - PROCESS | 16992 | __metrics Screen width/height:1600/1200 02:11:23 INFO - PROCESS | 16992 | colorDepth:24 02:11:23 INFO - PROCESS | 16992 | Browser inner width/height: 1024/697 02:11:23 INFO - PROCESS | 16992 | __metrics 02:11:24 ERROR - PROCESS | 16992 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:11:25 INFO - TEST-INFO | 16992: exit 0 02:11:25 INFO - Browser initialized. 02:11:25 INFO - Running cycle 1/1 for kraken test... 02:11:25 INFO - TEST-INFO | started process 17075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDRBwvP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:11:37 INFO - PROCESS | 17075 | RSS: Main: 152920064 02:11:37 INFO - PROCESS | 17075 | 02:11:39 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60860/tests/kraken/driver.html?testName=audio-beat-detection) 02:11:40 INFO - PROCESS | 17075 | RSS: Main: 209506304 02:11:40 INFO - PROCESS | 17075 | 02:11:43 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60860/tests/kraken/driver.html?testName=audio-dft) 02:11:44 INFO - PROCESS | 17075 | RSS: Main: 198549504 02:11:44 INFO - PROCESS | 17075 | 02:11:47 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60860/tests/kraken/driver.html?testName=audio-fft) 02:11:47 INFO - PROCESS | 17075 | RSS: Main: 265871360 02:11:47 INFO - PROCESS | 17075 | 02:11:50 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60860/tests/kraken/driver.html?testName=audio-oscillator) 02:11:50 INFO - PROCESS | 17075 | RSS: Main: 175230976 02:11:50 INFO - PROCESS | 17075 | 02:11:52 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60860/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:11:53 INFO - PROCESS | 17075 | RSS: Main: 179015680 02:11:53 INFO - PROCESS | 17075 | 02:11:56 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60860/tests/kraken/driver.html?testName=imaging-darkroom) 02:11:57 INFO - PROCESS | 17075 | RSS: Main: 214794240 02:11:57 INFO - PROCESS | 17075 | 02:12:00 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60860/tests/kraken/driver.html?testName=imaging-desaturate) 02:12:01 INFO - PROCESS | 17075 | RSS: Main: 213630976 02:12:01 INFO - PROCESS | 17075 | 02:12:04 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60860/tests/kraken/driver.html?testName=json-parse-financial) 02:12:05 INFO - PROCESS | 17075 | RSS: Main: 214433792 02:12:05 INFO - PROCESS | 17075 | 02:12:06 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60860/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:12:07 INFO - PROCESS | 17075 | RSS: Main: 204218368 02:12:07 INFO - PROCESS | 17075 | 02:12:09 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:12:09 INFO - PROCESS | 17075 | RSS: Main: 207482880 02:12:09 INFO - PROCESS | 17075 | 02:12:11 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:12:12 INFO - PROCESS | 17075 | RSS: Main: 217337856 02:12:12 INFO - PROCESS | 17075 | 02:12:15 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:12:15 INFO - PROCESS | 17075 | RSS: Main: 224448512 02:12:15 INFO - PROCESS | 17075 | 02:12:18 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:12:19 INFO - PROCESS | 17075 | RSS: Main: 220303360 02:12:19 INFO - PROCESS | 17075 | 02:12:20 INFO - PROCESS | 17075 | Cycle 1(1): loaded http://localhost:60860/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60860/tests/kraken/driver.html?testName=ai-astar) 02:12:21 INFO - PROCESS | 17075 | RSS: Main: 266006528 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | __start_tp_report 02:12:21 INFO - PROCESS | 17075 | _x_x_mozilla_page_load 02:12:21 INFO - PROCESS | 17075 | _x_x_mozilla_page_load_details 02:12:21 INFO - PROCESS | 17075 | |i|pagename|runs| 02:12:21 INFO - PROCESS | 17075 | |0;ai-astar;110;106;111;109;109;109;109;109;112;109 02:12:21 INFO - PROCESS | 17075 | |1;audio-beat-detection;158;126;159;205;125;150;152;127;191;126 02:12:21 INFO - PROCESS | 17075 | |2;audio-dft;161;160;160;162;160;163;160;161;161;161 02:12:21 INFO - PROCESS | 17075 | |3;audio-fft;73;74;75;151;75;75;78;74;132;74 02:12:21 INFO - PROCESS | 17075 | |4;audio-oscillator;92;94;150;95;93;91;124;93;93;126 02:12:21 INFO - PROCESS | 17075 | |5;imaging-gaussian-blur;117;116;116;118;116;117;118;116;117;117 02:12:21 INFO - PROCESS | 17075 | |6;imaging-darkroom;145;145;146;145;145;145;145;146;147;147 02:12:21 INFO - PROCESS | 17075 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;94 02:12:21 INFO - PROCESS | 17075 | |8;json-parse-financial;57;56;56;56;56;56;58;119;54;59 02:12:21 INFO - PROCESS | 17075 | |9;json-stringify-tinderbox;60;61;62;62;63;62;62;67;62;62 02:12:21 INFO - PROCESS | 17075 | |10;stanford-crypto-aes;79;79;78;79;84;84;81;85;81;87 02:12:21 INFO - PROCESS | 17075 | |11;stanford-crypto-ccm;124;121;129;118;129;120;123;120;190;131 02:12:21 INFO - PROCESS | 17075 | |12;stanford-crypto-pbkdf2;162;158;159;161;161;162;166;164;159;160 02:12:21 INFO - PROCESS | 17075 | |13;stanford-crypto-sha256-iterative;62;66;63;62;62;63;62;62;62;60 02:12:21 INFO - PROCESS | 17075 | __end_tp_report 02:12:21 INFO - PROCESS | 17075 | __start_cc_report 02:12:21 INFO - PROCESS | 17075 | _x_x_mozilla_cycle_collect,1850 02:12:21 INFO - PROCESS | 17075 | __end_cc_report 02:12:21 INFO - PROCESS | 17075 | __startTimestamp1463735541206__endTimestamp 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | ------- Summary: start ------- 02:12:21 INFO - PROCESS | 17075 | Number of tests: 14 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#0] ai-astar Cycles:10 Average:109.30 Median:109.00 stddev:1.57 (1.4%) stddev-sans-first:1.64 02:12:21 INFO - PROCESS | 17075 | Values: 110.0 106.0 111.0 109.0 109.0 109.0 109.0 109.0 112.0 109.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:155.00 stddev:28.14 (18.2%) stddev-sans-first:29.76 02:12:21 INFO - PROCESS | 17075 | Values: 158.0 126.0 159.0 205.0 125.0 150.0 152.0 127.0 191.0 126.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 02:12:21 INFO - PROCESS | 17075 | Values: 161.0 160.0 160.0 162.0 160.0 163.0 160.0 161.0 161.0 161.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#3] audio-fft Cycles:10 Average:88.10 Median:74.50 stddev:28.53 (38.3%) stddev-sans-first:29.73 02:12:21 INFO - PROCESS | 17075 | Values: 73.0 74.0 75.0 151.0 75.0 75.0 78.0 74.0 132.0 74.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:93.00 stddev:20.67 (22.2%) stddev-sans-first:21.37 02:12:21 INFO - PROCESS | 17075 | Values: 92.0 94.0 150.0 95.0 93.0 91.0 124.0 93.0 93.0 126.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 02:12:21 INFO - PROCESS | 17075 | Values: 117.0 116.0 116.0 118.0 116.0 117.0 118.0 116.0 117.0 117.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 02:12:21 INFO - PROCESS | 17075 | Values: 145.0 145.0 146.0 145.0 145.0 145.0 145.0 146.0 147.0 147.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:12:21 INFO - PROCESS | 17075 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:56.00 stddev:19.83 (35.4%) stddev-sans-first:20.92 02:12:21 INFO - PROCESS | 17075 | Values: 57.0 56.0 56.0 56.0 56.0 56.0 58.0 119.0 54.0 59.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.00 stddev:1.83 (2.9%) stddev-sans-first:1.74 02:12:21 INFO - PROCESS | 17075 | Values: 60.0 61.0 62.0 62.0 63.0 62.0 62.0 67.0 62.0 62.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.50 stddev:3.09 (3.7%) stddev-sans-first:3.12 02:12:21 INFO - PROCESS | 17075 | Values: 79.0 79.0 78.0 79.0 84.0 84.0 81.0 85.0 81.0 87.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:126.50 stddev:21.37 (16.9%) stddev-sans-first:22.54 02:12:21 INFO - PROCESS | 17075 | Values: 124.0 121.0 129.0 118.0 129.0 120.0 123.0 120.0 190.0 131.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.20 Median:161.50 stddev:2.44 (1.5%) stddev-sans-first:2.57 02:12:21 INFO - PROCESS | 17075 | Values: 162.0 158.0 159.0 161.0 161.0 162.0 166.0 164.0 159.0 160.0 02:12:21 INFO - PROCESS | 17075 | 02:12:21 INFO - PROCESS | 17075 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.00 stddev:1.51 (2.4%) stddev-sans-first:1.59 02:12:21 INFO - PROCESS | 17075 | Values: 62.0 66.0 63.0 62.0 62.0 63.0 62.0 62.0 62.0 60.0 02:12:21 INFO - PROCESS | 17075 | -------- Summary: end -------- 02:12:21 INFO - PROCESS | 17075 | 02:12:22 INFO - TEST-INFO | 17075: exit 0 02:12:22 INFO - TEST-OK | kraken | took 62996ms 02:12:22 INFO - SUITE-END | took 1175s 02:12:22 INFO - Completed test suite (00:19:35) 02:12:22 INFO - javascript benchmark 02:12:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3051.8626661800217, 3062.035458830468, 3086.66046734859, 3094.628644562652, 3118.672539383365, 5159.148740457829, 5186.09243382408, 5273.709945952971, 5283.233931079991, 5320.308359913211, 5255.894882102356, 5294.735263236842, 5312.415634280226, 5323.361196656397, 5336.0, 4716.221823399135, 4727.432708075019, 4881.243407271879, 4893.2170852663585, 4915.139850526161, 4855.053264613402, 4878.48775878533, 4901.068796928572, 4916.360873086512, 4922.08694088199, 4715.834945776898, 4739.73931433771, 4790.377250957354, 4820.204666230085, 4864.075825593126, 4835.0, 4847.733374664392, 4848.806047758086, 4862.756862156878, 4887.218045112801, 4673.556012178838, 4734.597559207472, 4825.493323201048, 4851.078295123951, 4871.537203965618, 5221.765020574046, 5309.548688361494, 5325.254464374991, 5327.121025030865, 5327.706976116312, 5611.0, 5698.173764804122, 5700.315962084576, 5700.543956483471, 5704.629199102086, 5512.145617429305, 5561.026820306471, 5588.385277619459, 5672.602917795756, 5721.34204566476, 5337.2527846101575, 5348.411674715778, 5362.249285100128, 5362.49056339651, 5363.9463605363835, 5894.233749612608, 5900.4984576310635, 5906.0, 5907.32065812437, 5911.467967882825, 5304.973475132677, 5415.593830462731, 5498.917516237259, 5512.118061110203, 5656.660600363992, 5658.434156584309, 5662.065759149777, 5709.115087161496, 5714.514266287329, 5715.771369145187, 5612.719364031782, 5618.6628802272, 5620.57845661577, 5625.662460252398, 5644.040513112781, 5498.80754173602, 5571.9442805572235, 5572.944270557243, 5619.988402087663, 5692.800751973661, 4346.152500262482, 4349.0, 4349.956500435019, 4350.347447882779, 4384.0355121873135, 3745.4756334113245, 3886.358750806142, 3896.5518965318784, 3939.1333906540517, 3945.349017412152, 5296.3379577552805, 5349.705766182819, 5353.812616558402, 5384.0, 5391.433899440581, 5690.430956904276, 5718.685472299064, 5728.856778580569, 5734.05388110957, 5735.4551317624755, 5161.3032240647035, 5312.043832110257, 5380.2736630555155, 5395.001924643953, 5426.647267927572, 4685.99251161002, 4707.811687532529, 4727.054589082198, 4728.267118596623, 4769.379980602569, 5962.25471816023, 5972.731227094791, 5975.232660621786, 5978.0, 6027.517798576104, 5505.697186654612, 5555.30558680165, 5603.887922241451, 5768.942310576925, 5775.249217601685, 5857.5313974881055, 5891.941080589139, 5905.43898329658, 5909.320428150818, 5929.169916211647, 5575.386707462095, 5588.944110558842, 5603.55171586264, 5604.271444712324, 5605.075162598126, 5553.694546799803, 5611.130274807411, 5634.971825140848, 5667.659940403589, 5706.657600543981, 5573.526250268773, 5575.832725018256, 5579.525740311957, 5580.86047848807, 5589.189567512769, 5509.256250406304, 5571.693556854412, 5575.1915972184615, 5576.832695019156, 5577.330720313588, 5343.198520221998, 5360.410354860885, 5370.167624018283, 5409.567234621142, 5455.454454554513, 5549.278593782782, 5565.1930470082425, 5577.3028371453565, 5608.7195640217005, 5623.493885550321, 5314.760835762323, 5447.155690867922, 5450.400455949763, 5454.018276710231, 5493.972530137324], "value": 5223.148284350009, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.834006478186, 619.516776914007, 627.6296984778983, 634.1059106659611, 639.9200099987502, 30631.008984416025, 31117.844410777943, 33047.83476082616, 33171.66828331716, 42449.15101697964, 40882.18235635285, 43883.341749873776, 43983.0, 43990.12019759611, 44071.338929916, 8574.614142363593, 8805.42764720291, 8808.955955220215, 8809.471431714086, 8817.338699597556, 8510.957445212796, 8656.653733850639, 8670.52312122833, 8673.783155421102, 8677.609507572175, 8607.655693772242, 8681.0, 8697.217250447458, 8699.304055675562, 8701.825963480727, 8583.871241931345, 8633.309335253227, 8650.43772154811, 8650.437721548142, 8650.697225597138, 8546.658133674708, 8638.222559969632, 8651.65393384264, 8667.479951202886, 8673.176048275403, 39071.609283907084, 39544.209115817524, 39570.80214598937, 39632.405513917314, 39643.20713585712, 3366.797992120456, 3385.238321377695, 3391.9830400847836, 3582.982085089558, 3668.211334563055, 31451.0, 32025.839870800497, 32161.517577236362, 32357.51463728046, 32850.83574582136, 4121.464209652756, 4171.770552619605, 4227.112306415656, 4245.193413251503, 4248.745075295492, 325765.0, 329656.0, 330184.9999999976, 330227.0, 330919.3454032738, 52026.47973520292, 55073.1739023915, 56721.14918276229, 57290.42709572934, 57454.0, 53540.0, 59168.40831591715, 59266.0, 59515.0, 59567.0, 15280.847191528164, 15317.846821531642, 15344.079355238722, 15380.0, 15386.230688465532, 11033.337999720043, 11882.643520694393, 11985.580504682293, 12677.809832852514, 12739.872601274054, 16316.34734610626, 16324.591885202964, 16325.755113673304, 16345.34618615266, 16349.673006539802, 6116.3577824328695, 6123.234595675541, 6207.503399728011, 6209.130721698965, 6276.340984196685, 974.2303580171726, 1070.4487189097622, 1105.6848798092665, 1115.4590023838432, 1145.3242586873785, 32087.679123208934, 32094.35811283808, 32157.35685286328, 32456.188595285308, 33000.0, 1100.8203756562052, 1113.6547670222258, 1128.3455595754606, 1179.4161889864572, 1204.476052916984, 16396.508104756875, 16985.40551080706, 17033.829661703472, 17061.57346066358, 17148.142592870307, 484058.0, 485723.0, 491958.0, 493483.0, 493566.0, 55810.0, 56126.43873561294, 56137.71931140318, 56206.71896640491, 56267.0, 1274.133589159362, 1281.2952875918209, 1338.2305174524492, 1370.6230786533583, 1396.9720605588034, 31530.36939261156, 32578.0, 32931.176720582174, 33006.0, 33172.83413583013, 47760.52239477561, 48131.75934120307, 48427.5157248423, 48508.5149148504, 48720.512794871596, 317477.0, 317778.0, 318537.0, 318621.0, 319863.0, 35045.0, 39666.206675865746, 40292.0, 40347.0, 43394.0, 46660.0, 47153.292700608836, 47294.290585640556, 47594.0, 47600.0, 40447.0, 40583.79708101559, 40744.59255407408, 41506.58493415027, 41952.790236048626, 39192.21615567616, 40301.596984029784, 41158.588414115475, 41195.7940210297, 41826.79086604548, 40190.598094018686, 43194.56805432031, 43333.34999975066, 43492.0, 43799.56200438082, 43609.0, 43687.78156109199, 43932.12135757203, 44695.55304446914, 44716.0, 33529.49705754464, 34623.82688086543, 34830.0, 34944.0, 35351.823240883634], "value": 19742.578344007845, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.984300269808, 399.1179493319763, 402.63762613647714, 404.2077528045032, 419.25163583004326, 13112.642943780855, 18838.43484695458, 19465.70801437978, 19525.0, 20862.269820556245, 14388.388528473904, 18271.08644567773, 18465.0, 19242.807571924277, 19782.70325945112, 5586.078297080987, 5746.051901436258, 5779.017567013597, 5786.334571524266, 5798.7100644967795, 5667.206591077253, 5756.8848623027725, 5764.394738552454, 5770.740316685759, 5774.6535207887455, 4141.358089496118, 6038.396160383948, 6352.110704501373, 6356.936430635704, 6495.6752162391695, 6374.840628984266, 6428.614283143003, 6445.968645016822, 6449.322821103787, 6461.741530338781, 5543.002259593271, 5650.943490565083, 5672.574556908248, 5682.857928551778, 5691.459311365414, 21956.267434536167, 22595.77404225953, 23407.531849363088, 23952.640710389183, 23983.160589379288, 2450.6814114165045, 2455.3861534616344, 2543.2878793937725, 2644.5504264274928, 3036.635603727567, 14030.298485075706, 14288.785668214985, 14417.207053612046, 14537.0, 14729.410823567045, 1925.894075825829, 1927.0, 1987.224982256921, 2005.0977060322914, 2058.279602139254, 75674.24325756782, 76918.6154069226, 77484.61257693732, 77795.0, 77964.61017694933, 10431.634892778868, 10459.84310235347, 10938.179636527293, 11014.779704405866, 11159.609413670483, 15024.17367044823, 15041.623959400884, 15084.094954302776, 15112.168830714416, 15136.470223542123, 7979.401544884041, 8079.0, 8250.46371985831, 8298.253157215879, 8320.167983201753, 7782.338501227346, 7864.72473463426, 7932.206779322021, 7944.0, 8008.27925486709, 6036.667983260875, 6328.430441260309, 6372.2671892732005, 6703.0, 6879.626449226608, 1872.0265461959705, 1930.2182616040527, 1934.6711059119984, 1962.8681625179079, 1994.7706013808308, 926.3191554207652, 989.5250279865561, 1034.2036631793628, 1087.597588892099, 1123.1239633086207, 19175.808241917402, 19872.0, 19874.60250794976, 19902.60194796096, 20085.59828803445, 1026.830572955454, 1034.715453250347, 1043.796459690353, 1063.9734006649896, 1077.7575045614674, 10923.836142457869, 11406.942965285287, 11483.368414737102, 11516.712082198012, 11524.481398337095, 415491.0, 422192.0, 422883.0, 423539.0, 424206.0, 83968.58015709883, 84047.0, 85353.0, 85572.57213713892, 85643.14356856352, 1034.0590063042746, 1043.6451606453843, 1048.4757621189558, 1055.3351388625117, 1071.4482041748506, 17510.0, 17632.206550705505, 17733.20200591002, 17849.37527186542, 18063.638727225643, 30712.53931190989, 37223.62776372202, 38497.23005539818, 39252.80373598228, 40455.59544404518, 52097.0, 53126.0, 53242.20136697875, 53744.0, 53806.730966346484, 7231.204567497466, 7605.809854753676, 8787.956060219914, 8893.688720894737, 9054.63781448761, 11045.33727976324, 13236.404361803934, 13350.732985340434, 13484.190948542726, 13976.091554048955, 12097.455614497545, 12119.697007574881, 12242.938785306016, 12276.938615307223, 12311.938440307742, 6672.966635166793, 9095.454272743656, 11711.531538738354, 11910.404479776149, 11933.105017123924, 12174.686809506165, 12220.938895305466, 12224.266544007389, 12286.631401057983, 12382.0, 8528.616212270339, 8721.781955451164, 8773.780655483664, 8903.065178156201, 8948.73153805387, 11494.942525287655, 11973.281603103842, 12006.339651318913, 12415.93792031034, 12437.875621243671], "value": 9367.578822016743, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.7366063617587, 1465.6702241995552, 1504.97742533862, 1666.5916850371661, 1835.2658936425428, 8000.639971201295, 8295.046069701979, 8311.501309921408, 8552.444091134072, 8567.91432085679, 8343.83312333753, 8515.105913879046, 8527.104654011317, 8538.231559159674, 8542.871856922151, 2003.2187446895723, 2237.899294531742, 2277.840551161415, 2291.232437133562, 2315.513742114158, 2370.8814559272055, 2375.0974629640714, 2375.3111597636666, 2378.702662167229, 2383.9642405363934, 2294.552562250362, 2312.28319221042, 2347.4835536182013, 2347.730011048726, 2350.095213342867, 1950.5318723506398, 2199.1313431194667, 2215.8559693619864, 2264.388615073921, 2264.4452109233343, 2253.5943530164564, 2273.1589311954517, 2294.5181511882524, 2298.0807676929194, 2298.390926404504, 8402.705905293285, 8518.829623407497, 8532.104129066483, 8536.189062039097, 8557.486550806909, 5248.422673505911, 5372.221027950925, 5391.595630327741, 5397.055515284848, 5397.163439666858, 3176.745860331168, 3183.1405520509566, 3230.951535726966, 3301.570795796531, 3341.1480072581335, 1032.7314898126467, 1035.5081336365242, 1037.1080870451406, 1038.6883934819523, 1045.3362115056993, 11521.48153333102, 11659.591914282959, 11665.591704290395, 11666.125040621988, 11670.649880503599, 4642.65180111493, 4717.905641887143, 4754.643401744883, 4755.262934245128, 4796.616270698336, 9894.752631184134, 9903.25725570585, 9909.75225619351, 9919.355241909252, 9921.801563968824, 5148.6910785353, 5198.324217851655, 5206.583473322125, 5210.76551555181, 5213.687178769286, 4109.0959988802415, 4573.314002899591, 4654.115718013567, 4660.277656963176, 4748.097861406391, 4049.6152865477734, 4058.857939972087, 4073.190765059249, 4084.611961863618, 4124.587541245912, 1258.1004581724112, 1271.4850485553366, 1272.3256673962815, 1286.2411177405377, 1309.3780454284138, 964.8263312603658, 973.6689525561202, 977.672479719302, 980.8136454073846, 990.5889056041806, 5637.633553818989, 5792.9710351447975, 5844.795432159854, 5851.561132915049, 5978.342382337939, 399.68025579536254, 400.6514332530717, 409.81763115413355, 410.9383592461095, 410.9773962432035, 4166.520850102277, 4180.184863951561, 4181.937270940938, 4184.539700632928, 4197.0, 10043.296969212231, 10360.948195259127, 10409.947950260352, 10438.478076096164, 10459.529321180566, 10088.596456141819, 10182.083612474913, 10182.796344073226, 10207.438590877424, 10213.131883789962, 7809.3752499799875, 7880.566568838654, 7909.169537198626, 7945.245201705829, 8123.350131989427, 5886.587938844411, 5952.315483719428, 5983.850403739942, 6081.027035674271, 6121.602095863755, 9456.621735130519, 9734.318597698093, 9787.412755234709, 9875.506224688881, 9942.254330925065, 9650.565724542554, 9661.082197191256, 9709.223262139154, 9813.509324533887, 9817.901820981699, 6740.764073257413, 7162.283771622796, 7293.197748247795, 7336.229695882069, 7520.736774212876, 8360.205780450848, 8379.287760540179, 8412.074671785977, 8478.02502712196, 8481.15188481147, 7114.608696521741, 7295.671694773641, 7319.304666056716, 7322.231165727735, 7417.740379086706, 7152.570845749272, 7165.0, 7181.06646135999, 7207.459440541876, 7235.604907316208, 6335.113084168128, 6381.361863813767, 6391.808245752635, 6401.391867772554, 6402.583832050902, 4885.145099607484, 4982.177940639754, 5034.496550344937, 5063.746812659426, 5083.974580127076, 4929.975350123226, 5064.037832811754, 5091.541761241358, 5160.793568257228, 5163.561097306771], "value": 4417.457506473411, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7881929697454, 320.9309998350355, 322.17487930871613, 330.08566271428145, 330.2256209189453, 5296.894062118756, 5298.735063246837, 5308.495692909177, 5317.8936421271555, 5322.308099947007, 5254.264402983586, 5260.237265596486, 5263.31576895004, 5290.603204759639, 5337.332833395825, 3996.880873355458, 4037.2934736421157, 4123.649489793356, 4142.5028996520305, 4190.769507677077, 4091.304478238692, 4093.672506199497, 4105.261053010457, 4120.464339635858, 4170.416141740159, 4079.6124368184974, 4083.183363327332, 4084.775337356444, 4093.2836753568154, 4134.937975930348, 3935.756618727341, 3944.4872166618297, 4136.131412403399, 4151.232022075926, 4162.687798415101, 3687.1888184599347, 3691.2617476504806, 3698.8335524901445, 3701.3522633539023, 3703.5000274962804, 5116.079105760961, 5135.12702840518, 5168.415792103948, 5171.095058364771, 5183.922241166386, 4552.314134660566, 4786.138495070886, 4788.61691064714, 4797.640176986705, 4820.783064762094, 5504.614676972614, 5510.421405752419, 5512.558995280408, 5531.3362396512275, 5551.278333816618, 4094.2016306820183, 4107.568705285932, 4110.095778928631, 4110.23960567293, 4119.629233368982, 5556.77772889088, 5832.562557808139, 5843.53251739864, 5878.823635290948, 5887.322957859858, 4041.7170798043853, 4046.1705350403095, 4075.1849630074103, 4076.144009757925, 4086.652634526039, 4128.339465685476, 4135.338345864708, 4136.21411931732, 4138.489451350236, 4139.0, 4574.5882870541145, 4740.976295118571, 4761.381020467317, 4796.952030479721, 4836.830710925101, 5439.891202175934, 5571.9721401392735, 5593.692346920877, 5627.396192085318, 5639.802606908738, 1438.4461982136952, 1439.4673970630934, 1445.2123592642035, 1452.7385070687167, 1460.6421426750514, 907.1155623267261, 915.6200176926634, 936.8922573904089, 939.9624015039459, 966.6665000574791, 5206.583473322125, 5417.458254174551, 5437.13005919051, 5457.945420545823, 5489.890202195934, 5787.614245666248, 5791.131330300489, 5819.0, 5821.854453638608, 5834.5121993892135, 5276.0, 5361.249425080492, 5396.757145928443, 5476.878179437762, 5483.533899618497, 3006.353633968707, 3010.5097976501334, 3025.561293612414, 3042.437149127374, 3052.404781067715, 5760.971195144081, 5904.261967254093, 5906.143609176645, 5920.31916329619, 5947.167396564484, 5745.6552606843725, 5825.941740582624, 5827.854303642358, 5829.300483941955, 5846.970765146232, 5858.2677165354335, 5890.175375447441, 5899.410058994066, 5932.525397968152, 5985.670788106609, 5435.266239057834, 5750.281214848144, 5750.913736294043, 5760.798372056958, 5781.8554536136935, 5598.244237027949, 5654.745536450787, 5677.148427735872, 5686.147077938343, 5695.715214239354, 5946.256717910262, 5950.155451809953, 5962.19510366095, 5978.372270911492, 5997.640141591519, 3814.1990182062077, 4053.6554392876938, 4058.7158898877974, 4060.9187816244103, 4066.796660167039, 5659.858503537444, 5659.943400565941, 5665.603407761582, 5694.886102278014, 5738.598298119092, 5323.973380133075, 5545.445455454423, 5554.222408862844, 5555.194496798023, 5588.972055139699, 5494.2582751328055, 5499.835004949858, 5507.834764957058, 5522.834314970557, 5538.889222215614, 5253.579713622978, 5387.461253874581, 5462.9180562292395, 5481.616286859881, 5492.697901615449, 5420.647657902224, 5615.775368985358, 5622.7469763861545, 5630.634008789416, 5635.182898479616, 5461.481159289861, 5499.285092937893, 5503.642263252876, 5511.4016935088375, 5523.6409633373705], "value": 4250.560670437718, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.8485987662343, 802.1216767639435, 818.1654712193563, 820.9384296177789, 834.9248567628912, 14654.926725366371, 14933.92533037336, 15126.394944202219, 15140.318685659144, 15229.619259518518, 13910.652233694163, 13963.720725585483, 14080.929595352009, 14138.363773630186, 14138.787918181235, 675.665545554951, 787.3307688464816, 789.4355535791908, 798.3573223555036, 806.8265322955549, 811.4806523824757, 817.6034623207739, 818.2431251092745, 819.2176471469733, 825.8059356051353, 846.9915300847005, 861.1861790607878, 862.1981557151846, 862.2326129744532, 862.9482231066124, 706.4101475268131, 826.7147833997261, 828.825946551225, 837.9455335403179, 855.7389996051202, 591.9704014799242, 595.6068994463633, 595.7795615622205, 598.6946657204857, 598.9431004054608, 14531.346089426, 14583.343749531297, 14639.48761793332, 14644.414223431157, 14754.336054877556, 13993.860061399357, 14321.856781432156, 14343.641408964755, 14386.712265754626, 14430.0, 11145.832812507819, 11175.385353805537, 11250.324980501196, 11366.37484938328, 11610.36143012134, 3805.124821291091, 3835.42468629705, 3878.2437424702193, 3898.590647981978, 3934.1148241645687, 58735.70632146855, 59019.7760990444, 59386.70306648482, 59487.10769338506, 59541.40458595445, 11401.828972565418, 11493.77012459746, 11601.883981160081, 11650.766984660258, 11731.589394371156, 20709.792902071087, 20901.79098209029, 20923.476913077295, 21346.78653213479, 21402.892985534974, 7926.722564710323, 7952.681892724342, 7964.920350796534, 8110.594470276463, 8324.583770811556, 4698.8355407560575, 4699.130660827758, 4810.542998415145, 4825.348577942003, 4826.927596086061, 3536.2750636119536, 3621.167131559756, 3631.7457777955287, 3634.6547078027547, 3639.6178401267484, 667.366002297819, 674.5042393840523, 695.8712638161957, 699.136566340569, 710.3961632612238, 708.2527933030702, 711.6868577825741, 739.1721272175091, 751.7331347371713, 761.3604572159413, 8775.990761062432, 9014.819703606023, 9223.76940576491, 9269.768255793659, 9338.0, 561.5703986450369, 600.7236671131241, 621.2141640824361, 635.1076737184263, 665.9334066593399, 4498.527654596286, 4588.449386073626, 4595.6783025187915, 4606.493285738566, 4732.503087175865, 71966.28033719701, 72008.63995679987, 72639.2736072643, 78543.60728196322, 79538.20461795424, 66921.6653916737, 67262.3273767256, 68102.31897680959, 68814.31185688179, 71337.64331178415, 63092.0, 63394.683026585495, 63465.0, 63925.36074639194, 64146.679266603365, 8687.522186279815, 8850.557472126497, 8863.784053986503, 8905.55472226399, 8911.331650126136, 39605.80197099112, 40770.79614601908, 40879.0, 41347.793261033505, 41761.373579395724, 55365.16952245639, 55591.72204139115, 55782.72108639431, 55872.72063639656, 56686.0, 12376.009919206623, 12577.87422125767, 12612.432440540015, 12892.935535322264, 13080.803787942998, 12678.366081695694, 12735.426905789447, 12883.677908052374, 13017.349132543524, 13424.328783560979, 10777.461126943464, 10852.0, 11430.085593152695, 11536.769264614828, 11688.88311116912, 11731.23746956445, 11778.293302401884, 11796.174267801172, 11838.112141589243, 11938.52245910154, 10907.181961352771, 11497.691486921443, 11531.884681153417, 11612.883871161519, 11716.531338746696, 9719.368241064309, 9914.454704991007, 9989.250806189419, 10000.499975001367, 10425.843612345669, 10186.236032297458, 10320.380777153094, 10360.0, 10637.680869573926, 10651.94674026656], "value": 6934.651709378867, "unit": "score"}], "name": "dromaeo_css", "value": 7078.51603196366, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 111.0, 109.0, 109.0, 109.0, 109.0, 109.0, 112.0, 109.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 159.0, 205.0, 125.0, 150.0, 152.0, 127.0, 191.0, 126.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 162.0, 160.0, 163.0, 160.0, 161.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 151.0, 75.0, 75.0, 78.0, 74.0, 132.0, 74.0], "value": 88.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 150.0, 95.0, 93.0, 91.0, 124.0, 93.0, 93.0, 126.0], "value": 105.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 118.0, 116.0, 117.0, 118.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 147.0, 147.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 56.0, 56.0, 56.0, 58.0, 119.0, 54.0, 59.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 62.0, 63.0, 62.0, 62.0, 67.0, 62.0, 62.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 79.0, 84.0, 84.0, 81.0, 85.0, 81.0, 87.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 129.0, 118.0, 129.0, 120.0, 123.0, 120.0, 190.0, 131.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 158.0, 159.0, 161.0, 161.0, 162.0, 166.0, 164.0, 159.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 60.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1533.3000000000002, "alertThreshold": 2.0}]} 02:12:22 INFO - Return code: 0 02:12:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:12:22 ERROR - # TBPL SUCCESS # 02:12:22 INFO - Running post-action listener: _resource_record_post_action 02:12:22 INFO - ##### 02:12:22 INFO - ##### Finished run-tests step (success) 02:12:22 INFO - ##### 02:12:22 INFO - Running post-run listener: _resource_record_post_run 02:12:22 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 106496; Write bytes: 537907200; Read time: 276; Write time: 434620 02:12:22 INFO - TinderboxPrint: CPU usage
13.1% 02:12:22 INFO - TinderboxPrint: I/O read bytes / time
106,496 / 276 02:12:22 INFO - TinderboxPrint: I/O write bytes / time
537,907,200 / 434,620 02:12:22 INFO - TinderboxPrint: CPU idle
8,274.1 (86.9%) 02:12:22 INFO - TinderboxPrint: CPU user
1,202.8 (12.6%) 02:12:22 INFO - TinderboxPrint: Swap in / out
0 / 0 02:12:22 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 94208; Write bytes: 247242752; Read time: 172; Write time: 273796 02:12:22 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 12288; Write bytes: 290664448; Read time: 104; Write time: 160824 02:12:23 INFO - Running post-run listener: _upload_blobber_files 02:12:23 INFO - Blob upload gear active. 02:12:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:12: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 02:12: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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:12: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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:12:23 INFO - (blobuploader) - INFO - Open directory for files ... 02:12:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:12:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:12:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:24 INFO - (blobuploader) - INFO - Done attempting. 02:12:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:12:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:12:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:28 INFO - (blobuploader) - INFO - Done attempting. 02:12:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:12:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:12:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:29 INFO - (blobuploader) - INFO - Done attempting. 02:12:29 INFO - (blobuploader) - INFO - Iteration through files over. 02:12:29 INFO - Return code: 0 02:12:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:12:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:12:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eec5682da89d7e4f192da23a52f6c5f72baea84b8318d3c0a608e7a70426435c99a2bdb133e44a45744d3db8035a8bb9196f086a13856494e4b5fd98e10bd93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb1bbe1f260ce27e3dbefef232fdf6593a5c8e432acce8f2b1ad86846e84bd7a2cb75ab688317ac4c4707d6c40679bf8258388e125fab8280380bc567fc77d56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85c8d9d0bf577de4a606d2c4a2e38e25824b9446fa9f0e1f42faf4333bec0412b77f23635db4630cf32f25f49ab72b0ee9ca9e5c3ca5138e4c776dfe9f2e60b5"} 02:12:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:12:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:12:29 INFO - Contents: 02:12:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eec5682da89d7e4f192da23a52f6c5f72baea84b8318d3c0a608e7a70426435c99a2bdb133e44a45744d3db8035a8bb9196f086a13856494e4b5fd98e10bd93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb1bbe1f260ce27e3dbefef232fdf6593a5c8e432acce8f2b1ad86846e84bd7a2cb75ab688317ac4c4707d6c40679bf8258388e125fab8280380bc567fc77d56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85c8d9d0bf577de4a606d2c4a2e38e25824b9446fa9f0e1f42faf4333bec0412b77f23635db4630cf32f25f49ab72b0ee9ca9e5c3ca5138e4c776dfe9f2e60b5"} 02:12:29 INFO - Running post-run listener: copy_logs_to_upload_dir 02:12:29 INFO - Copying logs to upload dir... 02:12:29 INFO - mkdir: /builds/slave/test/build/upload/logs 02:12:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.454251 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-20 02:12:29.231232) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 02:12:29.234910) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665 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/sha512/eec5682da89d7e4f192da23a52f6c5f72baea84b8318d3c0a608e7a70426435c99a2bdb133e44a45744d3db8035a8bb9196f086a13856494e4b5fd98e10bd93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb1bbe1f260ce27e3dbefef232fdf6593a5c8e432acce8f2b1ad86846e84bd7a2cb75ab688317ac4c4707d6c40679bf8258388e125fab8280380bc567fc77d56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85c8d9d0bf577de4a606d2c4a2e38e25824b9446fa9f0e1f42faf4333bec0412b77f23635db4630cf32f25f49ab72b0ee9ca9e5c3ca5138e4c776dfe9f2e60b5"} build_url:https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/XtwNK2xGROyY20UCLnhRBg/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/sha512/eec5682da89d7e4f192da23a52f6c5f72baea84b8318d3c0a608e7a70426435c99a2bdb133e44a45744d3db8035a8bb9196f086a13856494e4b5fd98e10bd93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bb1bbe1f260ce27e3dbefef232fdf6593a5c8e432acce8f2b1ad86846e84bd7a2cb75ab688317ac4c4707d6c40679bf8258388e125fab8280380bc567fc77d56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85c8d9d0bf577de4a606d2c4a2e38e25824b9446fa9f0e1f42faf4333bec0412b77f23635db4630cf32f25f49ab72b0ee9ca9e5c3ca5138e4c776dfe9f2e60b5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 02:12:29.259294) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 02:12:29.259654) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1463733482.211640-991825665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 02:12:29.342482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 02:12:29.342819) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 02:12:29.343108) ========= ========= Total master_lag: 0.14 =========