builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1463262048.78 results: success (0) buildid: 20160514130527 builduid: 2ff7712dfe204a41a0a665e4771196ee revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:48.783098) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:48.783813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:48.784117) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:48.805468) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:48.805885) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-14 14:40:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.1M=0s 2016-05-14 14:40:50 (42.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137278 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:48.956617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:48.956925) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018780 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:49.012584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:49.012915) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 403912ca555eb65f814b18ecf38ad8e8e98569f5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 403912ca555eb65f814b18ecf38ad8e8e98569f5 --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=74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-14 14:40:50,383 truncating revision to first 12 chars 2016-05-14 14:40:50,383 Setting DEBUG logging. 2016-05-14 14:40:50,383 attempt 1/10 2016-05-14 14:40:50,383 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/403912ca555e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 14:40:51,007 unpacking tar archive at: mozilla-central-403912ca555e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.919234 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:49.952224) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:49.952552) ========= script_repo_revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:49.952967) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:49.953232) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 14:40:49.962244) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-14 14:40:49.962600) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:40:51 INFO - MultiFileLogger online at 20160514 14:40:51 in /builds/slave/test 14:40:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:40:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:40:51 INFO - {'append_to_log': False, 14:40:51 INFO - 'base_work_dir': '/builds/slave/test', 14:40:51 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:40:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:40:51 INFO - 'branch': 'Firefox-Non-PGO', 14:40:51 INFO - 'buildbot_json_path': 'buildprops.json', 14:40:51 INFO - 'config_files': ('talos/linux_config.py',), 14:40:51 INFO - 'default_actions': ('clobber', 14:40:51 INFO - 'read-buildbot-config', 14:40:51 INFO - 'download-and-extract', 14:40:51 INFO - 'populate-webroot', 14:40:51 INFO - 'create-virtualenv', 14:40:51 INFO - 'install', 14:40:51 INFO - 'run-tests'), 14:40:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:40:51 INFO - 'download_minidump_stackwalk': True, 14:40:51 INFO - 'download_symbols': 'ondemand', 14:40:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:40:51 INFO - 'tooltool.py': '/tools/tooltool.py', 14:40:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:40:51 INFO - '/tools/misc-python/virtualenv.py')}, 14:40:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:40:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:40:51 INFO - 'installer_path': 'installer.exe', 14:40:51 INFO - 'log_level': 'info', 14:40:51 INFO - 'log_name': 'talos', 14:40:51 INFO - 'log_to_console': True, 14:40:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:40:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:40:51 INFO - 'opt_config_files': (), 14:40:51 INFO - 'pip_index': False, 14:40:51 INFO - 'sps_profile': False, 14:40:51 INFO - 'sps_profile_interval': 0, 14:40:51 INFO - 'suite': 'dromaeojs', 14:40:51 INFO - 'system_bits': '32', 14:40:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:40:51 INFO - 'title': 'talos-linux64-ix-042', 14:40:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:40:51 INFO - 'use_talos_json': True, 14:40:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:40:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:40:51 INFO - 'work_dir': 'build'} 14:40:51 INFO - ##### 14:40:51 INFO - ##### Running clobber step. 14:40:51 INFO - ##### 14:40:51 INFO - Running pre-action listener: _resource_record_pre_action 14:40:51 INFO - Running main action method: clobber 14:40:51 INFO - rmtree: /builds/slave/test/build 14:40:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:40:51 INFO - Running post-action listener: _resource_record_post_action 14:40:51 INFO - ##### 14:40:51 INFO - ##### Finished clobber step (success) 14:40:51 INFO - ##### 14:40:51 INFO - ##### 14:40:51 INFO - ##### Running read-buildbot-config step. 14:40:51 INFO - ##### 14:40:51 INFO - Running pre-action listener: _resource_record_pre_action 14:40:51 INFO - Running main action method: read_buildbot_config 14:40:51 INFO - Using buildbot properties: 14:40:51 INFO - { 14:40:51 INFO - "project": "", 14:40:51 INFO - "product": "firefox", 14:40:51 INFO - "script_repo_revision": "production", 14:40:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 14:40:51 INFO - "repository": "", 14:40:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 14:40:51 INFO - "buildid": "20160514130527", 14:40:51 INFO - "pgo_build": "False", 14:40:51 INFO - "basedir": "/builds/slave/test", 14:40:51 INFO - "buildnumber": 578, 14:40:51 INFO - "slavename": "talos-linux64-ix-042", 14:40:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:40:51 INFO - "platform": "ubuntu64_hw", 14:40:51 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 14:40:51 INFO - "branch": "mozilla-central", 14:40:51 INFO - "repo_path": "mozilla-central", 14:40:51 INFO - "revision": "403912ca555eb65f814b18ecf38ad8e8e98569f5", 14:40:51 INFO - "stage_platform": "linux64", 14:40:51 INFO - "builduid": "2ff7712dfe204a41a0a665e4771196ee", 14:40:51 INFO - "slavebuilddir": "test" 14:40:51 INFO - } 14:40:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:40:51 INFO - Running post-action listener: _resource_record_post_action 14:40:51 INFO - ##### 14:40:51 INFO - ##### Finished read-buildbot-config step (success) 14:40:51 INFO - ##### 14:40:51 INFO - ##### 14:40:51 INFO - ##### Running download-and-extract step. 14:40:51 INFO - ##### 14:40:51 INFO - Running pre-action listener: _resource_record_pre_action 14:40:51 INFO - Running main action method: download_and_extract 14:40:51 INFO - mkdir: /builds/slave/test/build/tests 14:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:40:51 INFO - https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:40:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:40:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:40:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:40:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:40:55 INFO - Downloaded 1361 bytes. 14:40:55 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:40:55 INFO - Using the following test package requirements: 14:40:55 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:40:55 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:40:55 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:40:55 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'jsshell-linux-x86_64.zip'], 14:40:55 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:40:55 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:40:55 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:40:55 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:40:55 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:40:55 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:40:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:40:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:40:55 INFO - https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:40:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:40:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:40:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:40:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:40:57 INFO - Downloaded 22447955 bytes. 14:40:57 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'] 14:40:57 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 14:40:58 INFO - Return code: 0 14:40:58 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 14:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:40:58 INFO - https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:40:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:40:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:40:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:40:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:41:02 INFO - Downloaded 22447955 bytes. 14:41:02 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'] 14:41:02 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 14:41:03 INFO - Return code: 0 14:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:03 INFO - https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:41:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:41:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:41:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:41:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:41:04 INFO - Downloaded 10840194 bytes. 14:41:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:41:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:41:05 INFO - Return code: 0 14:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:05 INFO - https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:41:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:41:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:41:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:41:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UAXmI3VEQA6LyDbc6-lcvw/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 14:41:06 INFO - Downloaded 54736994 bytes. 14:41:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:41:06 INFO - mkdir: /builds/slave/test/properties 14:41:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:41:06 INFO - Writing to file /builds/slave/test/properties/build_url 14:41:06 INFO - Contents: 14:41:06 INFO - build_url:https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:41:07 INFO - Running post-action listener: _resource_record_post_action 14:41:07 INFO - Running post-action listener: set_extra_try_arguments 14:41:07 INFO - ##### 14:41:07 INFO - ##### Finished download-and-extract step (success) 14:41:07 INFO - ##### 14:41:07 INFO - ##### 14:41:07 INFO - ##### Running populate-webroot step. 14:41:07 INFO - ##### 14:41:07 INFO - Running pre-action listener: _resource_record_pre_action 14:41:07 INFO - Running main action method: populate_webroot 14:41:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:41:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:41:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:41:07 INFO - '--tppagecycles', 14:41:07 INFO - '7'], 14:41:07 INFO - 'tests': ['tsvgm']}}, 14:41:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:41:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:41:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:41:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:41:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:41:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:41:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['damp', 'tps']}, 14:41:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'tests': ['damp', 'tps']}, 14:41:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['dromaeo_dom']}, 14:41:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:41:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['a11yr', 14:41:07 INFO - 'ts_paint', 14:41:07 INFO - 'tpaint', 14:41:07 INFO - 'sessionrestore', 14:41:07 INFO - 'sessionrestore_no_auto_restore', 14:41:07 INFO - 'tabpaint']}, 14:41:07 INFO - 'other-e10s': {'tests': ['a11yr', 14:41:07 INFO - 'ts_paint', 14:41:07 INFO - 'tpaint', 14:41:07 INFO - 'sessionrestore', 14:41:07 INFO - 'sessionrestore_no_auto_restore', 14:41:07 INFO - 'tabpaint']}, 14:41:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:41:07 INFO - 'ts_paint', 14:41:07 INFO - 'tpaint', 14:41:07 INFO - 'sessionrestore', 14:41:07 INFO - 'sessionrestore_no_auto_restore', 14:41:07 INFO - 'tabpaint']}, 14:41:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:41:07 INFO - 'ts_paint', 14:41:07 INFO - 'tpaint', 14:41:07 INFO - 'sessionrestore', 14:41:07 INFO - 'sessionrestore_no_auto_restore', 14:41:07 INFO - 'tabpaint']}, 14:41:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['a11yr', 14:41:07 INFO - 'ts_paint', 14:41:07 INFO - 'tpaint', 14:41:07 INFO - 'sessionrestore', 14:41:07 INFO - 'sessionrestore_no_auto_restore', 14:41:07 INFO - 'tabpaint']}, 14:41:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['a11yr', 14:41:07 INFO - 'ts_paint', 14:41:07 INFO - 'tpaint', 14:41:07 INFO - 'sessionrestore', 14:41:07 INFO - 'sessionrestore_no_auto_restore', 14:41:07 INFO - 'tabpaint']}, 14:41:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['tsvgx', 14:41:07 INFO - 'tsvgr_opacity', 14:41:07 INFO - 'tart', 14:41:07 INFO - 'tscrollx', 14:41:07 INFO - 'cart']}, 14:41:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:41:07 INFO - 'tsvgr_opacity', 14:41:07 INFO - 'tart', 14:41:07 INFO - 'tscrollx', 14:41:07 INFO - 'cart']}, 14:41:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'talos_options': ['--disable-e10s'], 14:41:07 INFO - 'tests': ['tp5o']}, 14:41:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'tests': ['tp5o']}, 14:41:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'talos_options': ['--disable-e10s', 14:41:07 INFO - '--xperf_path', 14:41:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:41:07 INFO - 'tests': ['tp5n']}, 14:41:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:41:07 INFO - 'talos_options': ['--xperf_path', 14:41:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:41:07 INFO - 'tests': ['tp5n']}}, 14:41:07 INFO - 'talos.zip': {'path': '', 14:41:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:41:07 INFO - Running post-action listener: _resource_record_post_action 14:41:07 INFO - ##### 14:41:07 INFO - ##### Finished populate-webroot step (success) 14:41:07 INFO - ##### 14:41:07 INFO - ##### 14:41:07 INFO - ##### Running create-virtualenv step. 14:41:07 INFO - ##### 14:41:07 INFO - Running pre-action listener: _resource_record_pre_action 14:41:07 INFO - Running main action method: create_virtualenv 14:41:07 INFO - Creating virtualenv /builds/slave/test/build/venv 14:41:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:41:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:41:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:41:07 INFO - Using real prefix '/usr' 14:41:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:41:07 INFO - Installing distribute.............................................................................................................................................................................................done. 14:41:09 INFO - Installing pip.................done. 14:41:09 INFO - Return code: 0 14:41:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:41: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')]} 14:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41: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')]} 14:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:41:09 INFO - Using env: {'DISPLAY': ':0', 14:41:09 INFO - 'HOME': '/home/cltbld', 14:41:09 INFO - 'LANG': 'en_US.UTF-8', 14:41:09 INFO - 'LANGUAGE': 'en_US:en', 14:41:09 INFO - 'LOGNAME': 'cltbld', 14:41:09 INFO - 'MAIL': '/var/mail/cltbld', 14:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:09 INFO - 'MOZ_NO_REMOTE': '1', 14:41:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:09 INFO - 'NO_EM_RESTART': '1', 14:41:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:09 INFO - 'PWD': '/builds/slave/test', 14:41:09 INFO - 'SHELL': '/bin/bash', 14:41:09 INFO - 'SHLVL': '1', 14:41:09 INFO - 'TERM': 'linux', 14:41:09 INFO - 'TMOUT': '86400', 14:41:09 INFO - 'USER': 'cltbld', 14:41:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:09 INFO - '_': '/tools/buildbot/bin/python'} 14:41:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:41:09 INFO - Cleaning up... 14:41:09 INFO - Return code: 0 14:41:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:41: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')]} 14:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41: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')]} 14:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:41:09 INFO - Using env: {'DISPLAY': ':0', 14:41:09 INFO - 'HOME': '/home/cltbld', 14:41:09 INFO - 'LANG': 'en_US.UTF-8', 14:41:09 INFO - 'LANGUAGE': 'en_US:en', 14:41:09 INFO - 'LOGNAME': 'cltbld', 14:41:09 INFO - 'MAIL': '/var/mail/cltbld', 14:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:09 INFO - 'MOZ_NO_REMOTE': '1', 14:41:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:09 INFO - 'NO_EM_RESTART': '1', 14:41:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:09 INFO - 'PWD': '/builds/slave/test', 14:41:09 INFO - 'SHELL': '/bin/bash', 14:41:09 INFO - 'SHLVL': '1', 14:41:09 INFO - 'TERM': 'linux', 14:41:09 INFO - 'TMOUT': '86400', 14:41:09 INFO - 'USER': 'cltbld', 14:41:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:09 INFO - '_': '/tools/buildbot/bin/python'} 14:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:10 INFO - Downloading/unpacking psutil>=3.1.1 14:41: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 14:41: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 14:41: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 14:41: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 14:41: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 14:41: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 14:41:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:41:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:41:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:41:12 INFO - Installing collected packages: psutil 14:41:12 INFO - Running setup.py install for psutil 14:41:12 INFO - building 'psutil._psutil_linux' extension 14:41:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:41:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:41:12 INFO - building 'psutil._psutil_posix' extension 14:41:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:41:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:41:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:41:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:41:12 INFO - Successfully installed psutil 14:41:12 INFO - Cleaning up... 14:41:12 INFO - Return code: 0 14:41:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:41: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')]} 14:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41: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')]} 14:41:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:41:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:41:12 INFO - Using env: {'DISPLAY': ':0', 14:41:12 INFO - 'HOME': '/home/cltbld', 14:41:12 INFO - 'LANG': 'en_US.UTF-8', 14:41:12 INFO - 'LANGUAGE': 'en_US:en', 14:41:12 INFO - 'LOGNAME': 'cltbld', 14:41:12 INFO - 'MAIL': '/var/mail/cltbld', 14:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:12 INFO - 'MOZ_NO_REMOTE': '1', 14:41:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:12 INFO - 'NO_EM_RESTART': '1', 14:41:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:12 INFO - 'PWD': '/builds/slave/test', 14:41:12 INFO - 'SHELL': '/bin/bash', 14:41:12 INFO - 'SHLVL': '1', 14:41:12 INFO - 'TERM': 'linux', 14:41:12 INFO - 'TMOUT': '86400', 14:41:12 INFO - 'USER': 'cltbld', 14:41:12 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:12 INFO - '_': '/tools/buildbot/bin/python'} 14:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:41:15 INFO - Installing collected packages: mozsystemmonitor 14:41:15 INFO - Running setup.py install for mozsystemmonitor 14:41:15 INFO - Successfully installed mozsystemmonitor 14:41:15 INFO - Cleaning up... 14:41:15 INFO - Return code: 0 14:41:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:41: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')]} 14:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41: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')]} 14:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:15 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 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:41:15 INFO - Using env: {'DISPLAY': ':0', 14:41:15 INFO - 'HOME': '/home/cltbld', 14:41:15 INFO - 'LANG': 'en_US.UTF-8', 14:41:15 INFO - 'LANGUAGE': 'en_US:en', 14:41:15 INFO - 'LOGNAME': 'cltbld', 14:41:15 INFO - 'MAIL': '/var/mail/cltbld', 14:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:15 INFO - 'MOZ_NO_REMOTE': '1', 14:41:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:15 INFO - 'NO_EM_RESTART': '1', 14:41:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:15 INFO - 'PWD': '/builds/slave/test', 14:41:15 INFO - 'SHELL': '/bin/bash', 14:41:15 INFO - 'SHLVL': '1', 14:41:15 INFO - 'TERM': 'linux', 14:41:15 INFO - 'TMOUT': '86400', 14:41:15 INFO - 'USER': 'cltbld', 14:41:15 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:15 INFO - '_': '/tools/buildbot/bin/python'} 14:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:15 INFO - Downloading/unpacking blobuploader==1.2.4 14:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:17 INFO - Downloading blobuploader-1.2.4.tar.gz 14:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:41:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:41:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:17 INFO - Downloading docopt-0.6.1.tar.gz 14:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:41:18 INFO - Installing collected packages: blobuploader, requests, docopt 14:41:18 INFO - Running setup.py install for blobuploader 14:41:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:41:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:41:18 INFO - Running setup.py install for requests 14:41:18 INFO - Running setup.py install for docopt 14:41:18 INFO - Successfully installed blobuploader requests docopt 14:41:18 INFO - Cleaning up... 14:41:18 INFO - Return code: 0 14:41:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:41:18 INFO - Using env: {'DISPLAY': ':0', 14:41:18 INFO - 'HOME': '/home/cltbld', 14:41:18 INFO - 'LANG': 'en_US.UTF-8', 14:41:18 INFO - 'LANGUAGE': 'en_US:en', 14:41:18 INFO - 'LOGNAME': 'cltbld', 14:41:18 INFO - 'MAIL': '/var/mail/cltbld', 14:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:18 INFO - 'MOZ_NO_REMOTE': '1', 14:41:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:18 INFO - 'NO_EM_RESTART': '1', 14:41:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:18 INFO - 'PWD': '/builds/slave/test', 14:41:18 INFO - 'SHELL': '/bin/bash', 14:41:18 INFO - 'SHLVL': '1', 14:41:18 INFO - 'TERM': 'linux', 14:41:18 INFO - 'TMOUT': '86400', 14:41:18 INFO - 'USER': 'cltbld', 14:41:18 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:18 INFO - '_': '/tools/buildbot/bin/python'} 14:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:41:18 INFO - Running setup.py (path:/tmp/pip-1ddrYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:41:18 INFO - Running setup.py (path:/tmp/pip-LHskLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:41:19 INFO - Running setup.py (path:/tmp/pip-uHjU0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:41:19 INFO - Running setup.py (path:/tmp/pip-FxPScK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:41:19 INFO - Running setup.py (path:/tmp/pip-se7tkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:41:19 INFO - Running setup.py (path:/tmp/pip-Fkl5PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:41:19 INFO - Running setup.py (path:/tmp/pip-4yqiya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:41:19 INFO - Running setup.py (path:/tmp/pip-2tbrJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:41:19 INFO - Running setup.py (path:/tmp/pip-mmiN6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:41:19 INFO - Running setup.py (path:/tmp/pip-xCCq4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:41:19 INFO - Running setup.py (path:/tmp/pip-HOei36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:41:19 INFO - Running setup.py (path:/tmp/pip-luNUQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:41:19 INFO - Running setup.py (path:/tmp/pip-vQNhwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:41:19 INFO - Running setup.py (path:/tmp/pip-0ky_KF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:41:19 INFO - Running setup.py (path:/tmp/pip-BuBwCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:41:19 INFO - Running setup.py (path:/tmp/pip-Zt9LAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:41:19 INFO - Running setup.py (path:/tmp/pip-zu_vEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:41:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:41:20 INFO - Running setup.py install for manifestparser 14:41:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Running setup.py install for mozcrash 14:41:20 INFO - Running setup.py install for mozdebug 14:41:20 INFO - Running setup.py install for mozdevice 14:41:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Running setup.py install for mozfile 14:41:20 INFO - Running setup.py install for mozhttpd 14:41:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Running setup.py install for mozinfo 14:41:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Running setup.py install for mozInstall 14:41:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:41:20 INFO - Running setup.py install for mozleak 14:41:20 INFO - Running setup.py install for mozlog 14:41:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:41:21 INFO - Running setup.py install for moznetwork 14:41:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:41:21 INFO - Running setup.py install for mozprocess 14:41:21 INFO - Running setup.py install for mozprofile 14:41:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:41:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:41:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:41:21 INFO - Running setup.py install for mozrunner 14:41:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:41:21 INFO - Running setup.py install for mozscreenshot 14:41:21 INFO - Running setup.py install for moztest 14:41:21 INFO - Running setup.py install for mozversion 14:41:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:41:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:41:21 INFO - Cleaning up... 14:41:21 INFO - Return code: 0 14:41:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:41:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:41:21 INFO - Using env: {'DISPLAY': ':0', 14:41:21 INFO - 'HOME': '/home/cltbld', 14:41:21 INFO - 'LANG': 'en_US.UTF-8', 14:41:21 INFO - 'LANGUAGE': 'en_US:en', 14:41:21 INFO - 'LOGNAME': 'cltbld', 14:41:21 INFO - 'MAIL': '/var/mail/cltbld', 14:41:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:21 INFO - 'MOZ_NO_REMOTE': '1', 14:41:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:21 INFO - 'NO_EM_RESTART': '1', 14:41:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:21 INFO - 'PWD': '/builds/slave/test', 14:41:21 INFO - 'SHELL': '/bin/bash', 14:41:21 INFO - 'SHLVL': '1', 14:41:21 INFO - 'TERM': 'linux', 14:41:21 INFO - 'TMOUT': '86400', 14:41:21 INFO - 'USER': 'cltbld', 14:41:21 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:21 INFO - '_': '/tools/buildbot/bin/python'} 14:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:41:22 INFO - Running setup.py (path:/tmp/pip-mEVi46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:41:22 INFO - Running setup.py (path:/tmp/pip-m4HWiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:41:22 INFO - Running setup.py (path:/tmp/pip-7Tx9cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:41:22 INFO - Running setup.py (path:/tmp/pip-Ws1UL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:41:22 INFO - Running setup.py (path:/tmp/pip-WXTUZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:41:22 INFO - Running setup.py (path:/tmp/pip-N5KJ4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:41:22 INFO - Running setup.py (path:/tmp/pip-LQS3Zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:41:22 INFO - Running setup.py (path:/tmp/pip-3emFxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:41:22 INFO - Running setup.py (path:/tmp/pip-U_quDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:41:22 INFO - Running setup.py (path:/tmp/pip-32ta9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:41:22 INFO - Running setup.py (path:/tmp/pip-J_BVvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:41:22 INFO - Running setup.py (path:/tmp/pip-3A_F4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:41:22 INFO - Running setup.py (path:/tmp/pip-yM4EwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:41:23 INFO - Running setup.py (path:/tmp/pip-aIdBzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:41:23 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)) 14:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:41:23 INFO - Running setup.py (path:/tmp/pip-zYqcX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:41:23 INFO - Running setup.py (path:/tmp/pip-gK3Cq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:41:23 INFO - Running setup.py (path:/tmp/pip-m6MYrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:41:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:25 INFO - Downloading blessings-1.6.tar.gz 14:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:41:25 INFO - Installing collected packages: blessings 14:41:25 INFO - Running setup.py install for blessings 14:41:25 INFO - Successfully installed blessings 14:41:25 INFO - Cleaning up... 14:41:25 INFO - Return code: 0 14:41:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:41:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:41:25 INFO - Reading from file tmpfile_stdout 14:41:25 INFO - Current package versions: 14:41:25 INFO - argparse == 1.2.1 14:41:25 INFO - blessings == 1.6 14:41:25 INFO - blobuploader == 1.2.4 14:41:25 INFO - docopt == 0.6.1 14:41:25 INFO - manifestparser == 1.1 14:41:25 INFO - mozInstall == 1.12 14:41:25 INFO - mozcrash == 0.17 14:41:25 INFO - mozdebug == 0.1 14:41:25 INFO - mozdevice == 0.48 14:41:25 INFO - mozfile == 1.2 14:41:25 INFO - mozhttpd == 0.7 14:41:25 INFO - mozinfo == 0.9 14:41:25 INFO - mozleak == 0.1 14:41:25 INFO - mozlog == 3.2 14:41:25 INFO - moznetwork == 0.27 14:41:25 INFO - mozprocess == 0.22 14:41:25 INFO - mozprofile == 0.28 14:41:25 INFO - mozrunner == 6.11 14:41:25 INFO - mozscreenshot == 0.1 14:41:25 INFO - mozsystemmonitor == 0.3 14:41:25 INFO - moztest == 0.7 14:41:25 INFO - mozversion == 1.4 14:41:25 INFO - psutil == 3.1.1 14:41:25 INFO - requests == 1.2.3 14:41:25 INFO - wsgiref == 0.1.2 14:41:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:41: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')]} 14:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41: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')]} 14:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:41:25 INFO - Using env: {'DISPLAY': ':0', 14:41:25 INFO - 'HOME': '/home/cltbld', 14:41:25 INFO - 'LANG': 'en_US.UTF-8', 14:41:25 INFO - 'LANGUAGE': 'en_US:en', 14:41:25 INFO - 'LOGNAME': 'cltbld', 14:41:25 INFO - 'MAIL': '/var/mail/cltbld', 14:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:25 INFO - 'MOZ_NO_REMOTE': '1', 14:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:25 INFO - 'NO_EM_RESTART': '1', 14:41:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:25 INFO - 'PWD': '/builds/slave/test', 14:41:25 INFO - 'SHELL': '/bin/bash', 14:41:25 INFO - 'SHLVL': '1', 14:41:25 INFO - 'TERM': 'linux', 14:41:25 INFO - 'TMOUT': '86400', 14:41:25 INFO - 'USER': 'cltbld', 14:41:25 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:25 INFO - '_': '/tools/buildbot/bin/python'} 14:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:41:25 INFO - Cleaning up... 14:41:25 INFO - Return code: 0 14:41:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:41: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')]} 14:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41: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')]} 14:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:41:25 INFO - Using env: {'DISPLAY': ':0', 14:41:25 INFO - 'HOME': '/home/cltbld', 14:41:25 INFO - 'LANG': 'en_US.UTF-8', 14:41:25 INFO - 'LANGUAGE': 'en_US:en', 14:41:25 INFO - 'LOGNAME': 'cltbld', 14:41:25 INFO - 'MAIL': '/var/mail/cltbld', 14:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:25 INFO - 'MOZ_NO_REMOTE': '1', 14:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:25 INFO - 'NO_EM_RESTART': '1', 14:41:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:25 INFO - 'PWD': '/builds/slave/test', 14:41:25 INFO - 'SHELL': '/bin/bash', 14:41:25 INFO - 'SHLVL': '1', 14:41:25 INFO - 'TERM': 'linux', 14:41:25 INFO - 'TMOUT': '86400', 14:41:25 INFO - 'USER': 'cltbld', 14:41:25 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:25 INFO - '_': '/tools/buildbot/bin/python'} 14:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:26 INFO - Downloading/unpacking jsonschema 14:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:41:27 INFO - Downloading/unpacking functools32 (from jsonschema) 14:41: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 14:41: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 14:41: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 14:41: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 14:41: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 14:41: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 14:41:28 INFO - Downloading functools32-3.2.3-2.tar.gz 14:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:41:28 INFO - warning: no files found matching '*.txt' 14:41:28 INFO - no previously-included directories found matching 'build' 14:41:28 INFO - no previously-included directories found matching 'dist' 14:41:28 INFO - no previously-included directories found matching '.git*' 14:41:28 INFO - Installing collected packages: jsonschema, functools32 14:41:28 INFO - Running setup.py install for functools32 14:41:28 INFO - warning: no files found matching '*.txt' 14:41:28 INFO - no previously-included directories found matching 'build' 14:41:28 INFO - no previously-included directories found matching 'dist' 14:41:28 INFO - no previously-included directories found matching '.git*' 14:41:28 INFO - Successfully installed jsonschema functools32 14:41:28 INFO - Cleaning up... 14:41:28 INFO - Return code: 0 14:41:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:41:28 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 0x7f31de58af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:41:28 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 14:41:28 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 14:41:28 INFO - Using env: {'DISPLAY': ':0', 14:41:28 INFO - 'HOME': '/home/cltbld', 14:41:28 INFO - 'LANG': 'en_US.UTF-8', 14:41:28 INFO - 'LANGUAGE': 'en_US:en', 14:41:28 INFO - 'LOGNAME': 'cltbld', 14:41:28 INFO - 'MAIL': '/var/mail/cltbld', 14:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:28 INFO - 'MOZ_NO_REMOTE': '1', 14:41:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:28 INFO - 'NO_EM_RESTART': '1', 14:41:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:28 INFO - 'PWD': '/builds/slave/test', 14:41:28 INFO - 'SHELL': '/bin/bash', 14:41:28 INFO - 'SHLVL': '1', 14:41:28 INFO - 'TERM': 'linux', 14:41:28 INFO - 'TMOUT': '86400', 14:41:28 INFO - 'USER': 'cltbld', 14:41:28 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:28 INFO - '_': '/tools/buildbot/bin/python'} 14:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:41:28 INFO - Downloading/unpacking flake8 14:41: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 14:41: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 14:41: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 14:41: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 14:41: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 14:41: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 14:41:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:41:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:41:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:41:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:41:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:41:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:41:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:41:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:41:31 INFO - no previously-included directories found matching 'docs/_build' 14:41:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:41:31 INFO - Running setup.py install for pep8 14:41:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:41:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:41:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:41:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:41:31 INFO - no previously-included directories found matching 'docs/_build' 14:41:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:41:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:41:31 INFO - Cleaning up... 14:41:31 INFO - Return code: 0 14:41:31 INFO - Running post-action listener: _resource_record_post_action 14:41:31 INFO - Running post-action listener: _start_resource_monitoring 14:41:31 INFO - Starting resource monitoring. 14:41:31 INFO - ##### 14:41:31 INFO - ##### Finished create-virtualenv step (success) 14:41:31 INFO - ##### 14:41:31 INFO - ##### 14:41:31 INFO - ##### Running install step. 14:41:31 INFO - ##### 14:41:31 INFO - Running pre-action listener: _resource_record_pre_action 14:41:31 INFO - Running main action method: install 14:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:41:31 INFO - Reading from file tmpfile_stdout 14:41:31 INFO - Detecting whether we're running mozinstall >=1.0... 14:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:41:31 INFO - Reading from file tmpfile_stdout 14:41:31 INFO - Output received: 14:41:31 INFO - Usage: mozinstall [options] installer 14:41:31 INFO - Options: 14:41:31 INFO - -h, --help show this help message and exit 14:41:31 INFO - -d DEST, --destination=DEST 14:41:31 INFO - Directory to install application into. [default: 14:41:31 INFO - "/builds/slave/test"] 14:41:31 INFO - --app=APP Application being installed. [default: firefox] 14:41:31 INFO - mkdir: /builds/slave/test/build/application 14:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:41:47 INFO - Reading from file tmpfile_stdout 14:41:47 INFO - Output received: 14:41:47 INFO - /builds/slave/test/build/application/firefox/firefox 14:41:47 INFO - Running post-action listener: _resource_record_post_action 14:41:47 INFO - ##### 14:41:47 INFO - ##### Finished install step (success) 14:41:47 INFO - ##### 14:41:47 INFO - ##### 14:41:47 INFO - ##### Running run-tests step. 14:41:47 INFO - ##### 14:41:47 INFO - Running pre-action listener: _resource_record_pre_action 14:41:47 INFO - Running main action method: run_tests 14:41:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:41:47 INFO - Python 2.7.3 14:41:47 INFO - Return code: 0 14:41:47 INFO - grabbing minidump binary from tooltool 14:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:41:47 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 0x19fd9c0>, '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 0x19fe8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19fed50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:41:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:41:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:41:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:41:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:41:47 INFO - Return code: 0 14:41:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:41:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:41:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:41:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:41:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:41:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:41:48 INFO - Return code: 0 14:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:41:48 INFO - Using env: {'DISPLAY': ':0', 14:41:48 INFO - 'HOME': '/home/cltbld', 14:41:48 INFO - 'LANG': 'en_US.UTF-8', 14:41:48 INFO - 'LANGUAGE': 'en_US:en', 14:41:48 INFO - 'LOGNAME': 'cltbld', 14:41:48 INFO - 'MAIL': '/var/mail/cltbld', 14:41:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:41:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:41:48 INFO - 'MOZ_NO_REMOTE': '1', 14:41:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:41:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:41:48 INFO - 'NO_EM_RESTART': '1', 14:41:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:41:48 INFO - 'PWD': '/builds/slave/test', 14:41:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:41:48 INFO - 'SHELL': '/bin/bash', 14:41:48 INFO - 'SHLVL': '1', 14:41:48 INFO - 'TERM': 'linux', 14:41:48 INFO - 'TMOUT': '86400', 14:41:48 INFO - 'USER': 'cltbld', 14:41:48 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012', 14:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:41:48 INFO - '_': '/tools/buildbot/bin/python'} 14:41:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:41:48 INFO - mozversion application_buildid: 20160514130527 14:41:48 INFO - mozversion application_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 14:41:48 INFO - mozversion application_display_name: Nightly 14:41:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:41:48 INFO - mozversion application_name: Firefox 14:41:48 INFO - mozversion application_remotingname: firefox 14:41:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:41:48 INFO - mozversion application_vendor: Mozilla 14:41:48 INFO - mozversion application_version: 49.0a1 14:41:48 INFO - mozversion platform_buildid: 20160514130527 14:41:48 INFO - mozversion platform_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 14:41:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:41:48 INFO - mozversion platform_version: 49.0a1 14:41:48 INFO - using testdate: 1463262108 14:41:48 INFO - actual date: 1463262108 14:41:48 INFO - starting webserver on 'localhost:46748' 14:41:48 INFO - SUITE-START | Running 2 tests 14:41:48 INFO - TEST-START | dromaeo_css 14:41:48 INFO - operating with platform_type : linux_ 14:41:48 INFO - Initialising browser for dromaeo_css test... 14:41:48 INFO - TEST-INFO | started process 26912 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIpSNc5/profile http://localhost:46748/getInfo.html) 14:41:52 INFO - PROCESS | 26912 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 14:41:53 INFO - PROCESS | 26912 | __metrics Screen width/height:1600/1200 14:41:53 INFO - PROCESS | 26912 | colorDepth:24 14:41:53 INFO - PROCESS | 26912 | Browser inner width/height: 1024/697 14:41:53 INFO - PROCESS | 26912 | __metrics 14:41:54 ERROR - PROCESS | 26912 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:41:54 INFO - TEST-INFO | 26912: exit 0 14:41:54 INFO - Browser initialized. 14:41:54 INFO - Running cycle 1/1 for dromaeo_css test... 14:41:54 INFO - TEST-INFO | started process 26994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIpSNc5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:42:06 INFO - PROCESS | 26994 | RSS: Main: 154677248 14:42:06 INFO - PROCESS | 26994 | 14:42:57 INFO - PROCESS | 26994 | 1463262177605 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:45:10 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:46748/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46748/tests/dromaeo/cssquery-ext.html) 14:45:11 INFO - PROCESS | 26994 | RSS: Main: 233680896 14:45:11 INFO - PROCESS | 26994 | 14:48:14 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:46748/tests/dromaeo/cssquery-ext.html (next: http://localhost:46748/tests/dromaeo/cssquery-jquery.html) 14:48:14 INFO - PROCESS | 26994 | RSS: Main: 234860544 14:48:14 INFO - PROCESS | 26994 | 14:51:16 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:46748/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46748/tests/dromaeo/cssquery-mootools.html) 14:51:17 INFO - PROCESS | 26994 | RSS: Main: 243851264 14:51:17 INFO - PROCESS | 26994 | 14:54:19 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:46748/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46748/tests/dromaeo/cssquery-prototype.html) 14:54:20 INFO - PROCESS | 26994 | RSS: Main: 235745280 14:54:20 INFO - PROCESS | 26994 | 14:57:23 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:46748/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46748/tests/dromaeo/cssquery-yui.html) 14:57:23 INFO - PROCESS | 26994 | RSS: Main: 215306240 14:57:23 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | Cycle 1(1): loaded http://localhost:46748/tests/dromaeo/cssquery-yui.html (next: http://localhost:46748/tests/dromaeo/cssquery-dojo.html) 15:00:26 INFO - PROCESS | 26994 | RSS: Main: 265129984 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | __start_tp_report 15:00:26 INFO - PROCESS | 26994 | _x_x_mozilla_page_load 15:00:26 INFO - PROCESS | 26994 | _x_x_mozilla_page_load_details 15:00:26 INFO - PROCESS | 26994 | |i|pagename|runs| 15:00:26 INFO - PROCESS | 26994 | |0;dojo.html;2703.959440608391;2716.1579910227824;2719.8096133270656;2745.5744359624255;2762.24038389443;4694.389729335182;4766.690165139264;4767.141914455401;4838.467768545457;4892.926606100911;4763.404574428197;4776.880577985552;4804.519548045194;4861.173600487917;4864.027194561093;4329.264025115722;4331;4414.249577571804;4440.045390241097;4519.954800452003;4463.3081872309685;4464.330350447426;4478.865634030968;4502.729836209838;4535.274356103024;4298.140371925596;4343.348497725335;4378.10248898975;4409.4708634963845;4438.977805110953;4334.566543345672;4344.196323680129;4346.326319420495;4385.232584297767;4386.627136693385;4356.89107772305;4364.541723119059;4379.890502737425;4385.868423947287;4411.360352748833;4626.352310676508;4639.512851150615;4685.906281874343;4701.835435759732;4708.4820669726305;5101.846944591631;5113.846584602431;5117.590592752571;5163.122269214243;5172.405173405069;5096.108181068335;5137.897242055175;5139.922901156486;5181.818636347747;5193.714345710984;4681.602063824579;4800.1119792838435;4838.879028024257;4854.126257273725;4912.606991440676;5372.838814835562;5389.110796718497;5394.433584473652;5396.64921780083;5397.8110766123;5137.8201762938115;5147.845564633067;5178;5181.481851814789;5198.298229738937;5032.72320022395;5113.258577506241;5170.1469257572835;5172.715500647425;5189.247559103985;5129.8717532061255;5132.076226279306;5143.639945203875;5153;5179.378474583074;4887.608991280689;5106.693598384109;5124.897502049938;5125.8718532037;5132.101882170605;4371.584699453547;4381.452318460192;4415.183191109655;4426.22541055314;4439.289713645866;3833.0609000794425;3846.211526637033;3848.5574158972076;3870.1485673152424;3886.630770076838;4287.914241715211;4421.602055814992;4428.822847086145;4434.600885920283;4475.776211189427;5077.619178561623;5128.589712822965;5132.538071573502;5150.995555866645;5152.9742351288005;4370.366296886934;4395.802188901507;4397.164538737694;4399.84600538986;4444.533324000933;4535.591796738309;4617.12274667812;4650.162970665313;4652.697574657636;4692.882677933079;4817.975910120427;4823.2282834746275;4846.684965477232;4851.054044461296;4890.535399137077;5115.974420127875;5124.769385377742;5126.589872810092;5130.640855140105;5160.793568257228;4735.810567577259;4809.495003024632;4825.155597770306;4827.61379089679;4857.0528746894015;5073.695578265316;5088.135017047185;5093.2105523643895;5097.6941383517105;5146.665466744649;5155.304033955518;5156.9742151289;5158.3552055993005;5161.690298582097;5195.376554813447;4877.561019508261;4955.108080545537;4964.05682920244;4988.975055124701;4994.600431965361;4968.975155124202;4975.477574854588;4980.750962451935;5000.974995125001;5051;4909.042736666316;4915.262710593297;4919.409670839522;4970.030843985388;4970.229614409773;4888.951110488992;4992.925106123338;5008.799648014038;5047.091523525653;5053.823116191063;4907.607391408607;4927.137750893651;4933.112039832865;4936.605071594336;4974.651774375759 15:00:26 INFO - PROCESS | 26994 | |1;ext.html;561.8089849451188;594.7472324262187;607.5261296188975;608.6561092982467;614.8647297594529;28845;43288.78355608226;44652.3302150468;44675;44788.55211447889;42915.14169716604;43328.56671433285;43332.783336083274;43347.13305733883;43420.13159736803;8017.839643207118;8129.2277233663;8135.593220338989;8138.470999385051;8139.593020348989;7943.960280198591;8018.599070046504;8020.478668886532;8032.879506807373;8040.758777236663;8084.5149291042435;8120.75637730869;8124.903138076344;8127.95936020322;8130.674773009073;7975.042994840598;8037;8047.718329858485;8070.717524886601;8075.5154690718155;7927.563983980879;8014;8024.117347091816;8046.275835174803;8047.356211503065;39711.20577588432;40240.59759402398;40314.59685403138;40332.39501407481;40376.39435408471;3378.7465940054594;3516.191276006533;3569.5359603251413;3608.6932610728118;3810.3522401191585;27666.723332766614;28000.29999250035;31485;32110.357792844246;32587.6741232587;3399.388110140174;3401.48977653354;3407.6762707542994;3425.3149370125825;3459.100633835196;257910;258752;258841;259210;259866;34543.30913381718;35736;58300;58801.4119858796;58999;36725.81637091797;36768;36935.815320923226;36940.26119477649;36944.96802675825;14705.55883323502;14846.480373186887;14849.777253341208;14850.92574537142;14852.331645075998;12209.450574724158;12258.877411225774;12293;12330.876691232972;12340.259584424963;8600.8709869352;8623.223909848019;8629.309655227567;8691.304695624334;8697.565121743888;3372.072680012967;3373.089265898243;3374.156460884779;3380.695737383598;3384.7969121852766;931.4504442379028;989.3223142147652;1015.9847602285971;1038.9272750907364;1052.347544522401;29050.41899162005;29337.85331073331;29506.262343441587;30217.244568885955;30245.546316805267;953.4565297780199;971.3249291742171;1011.9595216191417;1047.9991608014327;1062.3041907550567;15618.3752649895;16010.439634612732;16061.277242524115;16130.354785808437;16371.918140409221;378327;378559;382802;383090;383438;54518.45481545213;55197.72401138049;55275.170872436946;55279.72360138174;55466.44533554694;1130.9943450282697;1251.0992085698283;1257.5158563952943;1266.7973124300252;1267.6704056945446;29256.853715731286;30438;30466.390672186877;30931.536026960064;31106;44487;44798.328025079;45006.0998780016;45071.77464112658;45126.54873451355;282776;283749;284088;284799;285175;24080.397990050387;24689.753102469465;26195.607065893644;27242.318942026606;27387;44722.55277447314;45783;45842.770786145855;46389;46900.06199876052;38630;38872.80563597277;39326.41010384904;39331.80334098311;39627.405588916765;37862.62137378591;37958;38403;38773.80613096916;39147;38402;38851.222975539764;40220.19559608733;41746;43148.352774707775;33412.83293583516;35022.82488587541;35228.82385588055;35705.28589428145;35867;24525.38686532851;24556.19527407935;24917.750822491544;24932.37669058288;25019.12433064834 15:00:26 INFO - PROCESS | 26994 | |2;jquery.html;386.86653104678874;387.9786611736355;388.93582558877785;400.89376315276473;413.80551140963735;13462;13873.028887977845;17199.57001074972;18034.81965180348;18065.729014064782;17106.65786684269;17770.555736106602;18559.628807423844;18565.071746412657;18615.90692046538;5163.974180129104;5277.445868183843;5321.042212401766;5341.706206158679;5360.249565060894;5154;5195.376554813409;5277.788888444457;5286.471352864722;5328.706921119337;5260.55285300748;5267.26258323835;5274.4198138204765;5275.36695596529;5275.947240527603;5167.457416971239;5206.479352064782;5288.576913846902;5300.734963251842;5317.946820531823;4738.241881298975;4759.428868535763;4765.618750499986;4767.118083154627;4768.260919557474;20765;21113.68329475059;21909.671354929687;21948.122075116975;22017;1950.0932066589105;1957.07039156401;1986.2154448992712;2071.2336435518955;2087.5929193807215;13326.067720180616;13487.595372138852;13555.796663050061;13750;14307.213103279315;1806.3406856497415;1827.7898041725239;1863.2174486715612;1869.392447454569;1883.7551118354663;70071.64964175147;70486;71680;71951.64024179845;72238.63880680615;9903.900960990299;10040.598376064876;10225.290298071017;10244.334118282288;10457.320274182153;14088.15471071739;14141.646458838612;14165.645858853404;14166.220857852712;14210.786838197435;7708.354127158332;7863.567503787347;7969.880451793228;8025.478343907627;8143.063547691972;7541.8114547135665;7620.961895190489;7748.728794492278;7776.84446311082;7789.532628042336;6407.391297826699;6452.709628066748;6452.838679033061;6530.542861999615;6561.639109848908;1831.5512699388469;1860.451166905739;1882.4164509002253;1883.1996401529295;1883.9717404238947;888.5912480259024;893.8301722672671;895.9776005599912;938.9671361502315;1039.1323245090282;17528.561785955197;17619.295228190727;17710.557236069202;18056.548586285186;18447;854.5940678177816;930.2697382554662;941.8634298026885;945.5555888732422;954.9856752148723;10291.897081029243;10542;10556.419396933243;10572.629957951434;10641.521131548943;374241;378846;391989;392071;392222;67155.66422167956;70385.2961470389;71374.64312678507;74251.62874185595;82609.58695206486;928.456852741154;935.9064093590723;952.7094236258008;964.0455948610964;972.6109556177444;16060.67878642444;16096.436624718079;16268.186590670182;16474.58813529671;16535.033115880397;38097.4285385725;38313.4252986211;38580.807095965465;39743.20513589654;40220.597794022855;53830.192547111044;53965.190522142984;53983;54358.82564697368;54709.72645136749;6887.449004079762;6957.2694867038235;7218.747343842941;7279.017332660023;7484;11299.265547739371;11929.46317415701;11974.521019159136;12026.819597705866;12062.51749929993;10033.1973442123;10359.430231337348;10446.843297350393;10734.194935380034;10784.784304314027;10310.793784124426;10423.791524169626;10503.159747220052;10522.579096836042;10822.458877056273;10263.84604230952;10397.16822654201;10416.32293900894;10474.68575942723;10933.125349971824;7348.96325518369;7459.925400745923;7469.1037075551285;7502;7668.194839541991;10402.687919362432;10693.198010149114;10722.678319650424;10757.408342541235;10838 15:00:26 INFO - PROCESS | 26994 | |3;mootools.html;1352;1507.9623009424765;1545.1424459425018;1682.8653707703377;1823.8449731772798;7064.894026589599;7891.053073631165;8113.350931925446;8134.715284965027;8334.624941877615;8294.502329860212;8301.252887240147;8392.118827523114;8393.328533717304;8398.832023359544;1950.0834607734414;2239.32820153954;2288.096202000209;2318.976810231893;2319.1535089692225;2329.592321343758;2386.427257458215;2386.976130238693;2389.1279682915697;2391.629297458897;2297.908083676651;2314.8148148148193;2323.0939933425975;2329.8369114161933;2333;1888.0748433267738;2134.2423439678846;2227.175944900381;2231.0518029837335;2273.6816845641542;2275.7382900966436;2291.0377641390655;2297.4945511987335;2299.8850057497225;2300.9654855177187;8147.551884646342;8201.056878458994;8205.917940820635;8209.71266005693;8213.178682131798;4633.096546173498;4709.034647897173;4723.385959825201;4724.078804633098;4727.101850648366;2785.832850029005;2836.375997280595;2962.259435141215;2986.8655910483862;2991.341904780945;939.5208443693697;947.5215016416735;956.7942892278124;966.4781018250136;968.6077138758743;10477.214208934283;10482.895171048269;10488.160947124212;10489.160867130611;10503.52734126966;4268.317069268902;4311.892202694958;4403.911921761547;4422.336649502536;4422.557744225616;8954.641814327353;8991.685291014783;8993.595288212045;8998.235150012322;9011.369204155777;4810.4467986182035;4810.879728006757;4817.301491283744;4818.132736107465;4819.662623616313;3981.7810020449156;4292.93560596585;4371.169477799272;4447.577480139382;4468.262736648481;3888.766673999569;3898.6686131679126;3929.76421414716;3969.0871099647243;3997.2405243003736;1254.3414707278753;1272.1667307913146;1273.6943133647858;1302.9674258143623;1317.1767645221737;755.8223817402906;888.5113187746714;900.5632268349846;909.8726178335038;939.8120375924842;5331.86670333237;5536.86157846049;5548.029094908375;5640.8871822563315;5667.716614169358;415.3209502463434;418.84293389978757;427.53398795313257;431.4822213344;431.82079437033906;3507.3511400391008;3531.205478767257;3572.8570857165423;3575.7318201135017;3660;9483.810323793485;9685.41887486753;9788.951055244679;9798.31411801167;9815.263855210887;10018.749531261778;10038.996100389902;10044;10067.69796906094;10113;7165.211826699059;7629.847403052019;7684.884726729104;7708.9229107709325;7723.382129429632;5508.586855985737;5531.9723401382735;5557.722113894209;5560;5648.774049037993;9104.908950910407;9144.405613635092;9189;9271.76820579491;9277;8649.394542382224;8991.685291014783;8994.280457563511;9267.53662316895;9289.535523223947;6402.103705481143;6614.6361950093205;6622.8344291393105;7206.207317195209;7362.705491780269;7250.238724933806;7353.154387245535;7639.618019099134;7823;7846.254605812439;7149.857002860018;7289.45329100309;7341.449391295568;7376.668049937873;7422.1835598085245;6652.933470665232;6918.066061081689;7136.250693677089;7172.748953786592;7296.379807716404;6254.718537665907;6467.450266727381;6472.093906853139;6472.288048314587;6480.773172938924;4728.148933192058;4747.620190384692;4748.833790817304;4774.928376074292;4825.806967721252;4747.216709242936;4889.188043776417;4953.7523123844385;5019.02129084825;5027.698338099725 15:00:26 INFO - PROCESS | 26994 | |4;prototype.html;303.99848000759977;317.18325312320775;324.82134825845793;327.63468732363424;330.10541432717366;4850.175470170071;4870.147724148278;4893.632977526682;4896.314515967759;4896.804127834882;4552.226121559334;4600.263957766758;4638.582527572517;4757.738324392157;4829.565339119478;3776.7733935963793;3788.109794198375;3819.197968426634;3825.5409350877935;3857.556381016191;3845.903852403684;3850.345441274976;3870.9419358709642;3871.399933010388;3880.6895448364203;3593.8203089845533;3662.414013757786;3744.868929587465;3793.468914351993;3799.164183879542;3753.8123093845197;3760.6051364606733;3783.981080094582;3786.678132358753;3803.163304073127;3369.444041733111;3374.3926093303194;3380.7126394256516;3382.2051817822794;3385.6783605557434;4342.761148136851;4608.124456353282;4612.492625811158;4618.95381046192;4623.491415944277;4298.484181898192;4344.413504176927;4349.956500434987;4363.890902727426;4369.694121411502;5051.1413059779925;5054.519820617035;5054.620903432257;5070.188769796815;5073.46728593496;3570.7875476138224;3636.654517820803;3652.397354436515;3661.8718344858066;3668.211334563055;5155.587552995751;5252.290940722992;5253.213907271508;5256.237845512379;5258.1849813279005;3698.20488594948;3699.445083237482;3699.687695190506;3701.5193922431245;3702.389105797567;3752.1932784451474;3755.267722794029;3793.886183414502;3796.7911764853197;3807.428885667117;4011.478507793968;4243.278642630698;4252.914941701149;4355.847545335898;4356.586124318184;5002.59979201663;5008.12357837377;5033.496650335011;5054.898902021939;5085.567726743194;1219.1405059433077;1279.3731071774764;1294.6245588779148;1296.935153242334;1309.9520383693007;830.9364014062011;842.3598065470287;846.5132548784351;854.8162145138825;863.7883718488885;4034.789563131049;4038.63652271297;4061.1877624475223;4071.9796401017807;4072.9389059164137;4628.884277893079;4673.415823022122;4684.648651351163;4688.413948256468;4707.458642256184;3966;4023.4970628671417;4032.697547683935;4082.510098788164;4122.113745544662;2351.8824058797336;2460.7047154341235;2477.8884950177285;2504.5491811473744;2505.6993160820816;4339.240632889295;4358.912821743547;4434.866953991385;4447.466304043535;4449.977750111293;4715.929261061086;4730.763461826895;4740.265258884879;4744.833930812474;4792.353032340659;4085.611866872642;4417.381566580745;4426.380306757057;4436.822527098944;4440.756588155322;4591.4949355571525;4608.3778689876435;4635.281531362644;4639.5360463954685;4669.48635650085;4515.729056256635;4577.748223847587;4602.217623004164;4611.201631363838;4655.674102812771;4362.062156636339;4436.378906953094;4450.553570089798;4522.276435770261;4981.2030075187795;3747.3816820225447;3761.3417651911354;3765;3780.6030366811087;3782.867599634;5045.798168073383;5207.739613019258;5208.895822083613;5243.213517972335;5252.921206181833;4940.456549779593;5046.823361182341;5055.823046193365;5078.796848126033;5086.669366491166;5021.598272138147;5028.195488721787;5031.597472202142;5034.263179203169;5079.984003199449;4897.289892965571;5026.271190677405;5027.59227416324;5029.535527591527;5035.345405097314;5109.259157422079;5129.538341549279;5129.640925135204;5140.177571588528;5144.382674079135;4989.525995030466;5022.447530771539;5029.1953287473825;5038.244263360379;5049.182294374096 15:00:26 INFO - PROCESS | 26994 | |5;yui.html;737.4137560639291;760.8592410404076;761.5735188294556;780.4146889832625;794.8529522038423;11272.943635281823;11770.411479426037;11835.881641183585;11910.22583532072;11912.404379781008;13162.93418532906;13185.670358241048;13207.537736179236;13220.206787592751;13233.669158271048;663.9203295604534;844.1853611265128;865.9307255419551;877.5261358866204;892.2683399612321;870.1124852650292;879.4855009819263;883.5493898111977;884.3102380143498;884.3765145572356;942.6417961174744;944.4286206844863;944.4427787605312;944.5418971798672;944.8063147054871;709.8048036789872;909.8316811389848;930.3952430919888;931.753085432361;936.5972631768337;695.4922906278445;695.6799872058859;695.9095317608678;696.0116634379194;696.9965150174216;12707.364631768374;12715.682107947283;12732.17240879349;12747.36263186837;12755.744885102338;11837.348945807977;11856.762864742743;12216.694582635417;12423.93788031063;12489.875101248963;11084.334939903632;11119;11211.15916306272;11300.604478843283;11595.072394208442;4193.622573968358;4230.78846057699;4236.0892408132095;4239.512456067561;4299.871003869889;47821.28268075982;47851.52148478505;49288;49447.75276123633;49529.25706114411;9593.328467007383;9849.359791613522;9909.603615855429;9968.152707019839;9987.750306242402;16780.328786848633;17050;17338.479845604652;17386.47840564785;17524.29902803899;6627.204735431683;6853.143357080365;6898.586084834926;6936.341047600571;6948;3785.280796648628;3848.6536211741077;3912.6478616924614;3971.6028397160053;4056.290149223874;3054.57235986962;3170.3659268146002;3176.38060578185;3177.396294703999;3177.428062948645;551.3273805956726;555.5305766627193;566.0772940107598;578.5660754434174;582.6620560075146;600.8828278485653;627.6234259444296;630.9337519560472;637.3053371824733;639.4404895716249;8258.339332853357;8266.669333226724;8337.416380853403;8342.499450032896;8486.236238738544;558.4276116980128;593.8248216776061;610.8075956073823;613.414189449075;623.5790841182184;3751.3810221313724;3908.218356328746;3974.3442332014893;4055.6755459563756;4104.671626269891;57873.42126578765;58003.12995305074;58766.41233587695;59899.401005989384;60143;51561;52337.7383113082;52492.475075249524;52665.7366713164;54627.453725463034;47657;47954.9999999993;48342.5165748345;48423.5157648426;48453.75773121112;7658.578778167254;7805.102413222325;7838.294553490213;7851.646675899484;8025.8796118059445;32175.356492869545;32252.35495290128;32331;33000.834995824865;33127.50308745419;41101;41972.37041444442;42166.36750448684;42479.150416990866;42655;9840.65557705477;9965.451900145561;9975.800483990424;10173;10517.316374435637;11027.779444411228;11036.724081898017;11359.432028398713;11528.59649912249;11702.531898724295;10429.739256518344;10495.422751748727;10546.789064218827;10579.15366770641;10772.407517586607;10567.471626418803;10669.8933010672;10790;10831.133509319394;11038.668839934815;9771.609135634495;10005.149562287286;10403.635872744419;10986.395748233925;11179.049780768726;9003.459792412476;9172.587233574372;9404.858927116236;10077.244206684674;10314.587416503557;9438.905610943802;9527.857082143912;9551.904480955101;9590.280728945496;9642.373245739005 15:00:26 INFO - PROCESS | 26994 | __end_tp_report 15:00:26 INFO - PROCESS | 26994 | __start_cc_report 15:00:26 INFO - PROCESS | 26994 | _x_x_mozilla_cycle_collect,1083 15:00:26 INFO - PROCESS | 26994 | __end_cc_report 15:00:26 INFO - PROCESS | 26994 | __startTimestamp1463263226801__endTimestamp 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | ------- Summary: start ------- 15:00:26 INFO - PROCESS | 26994 | Number of tests: 6 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | [#0] dojo.html Cycles:165 Average:4749.84 Median:4877.56 stddev:496.23 (10.2%) stddev-sans-first:471.09 15:00:26 INFO - PROCESS | 26994 | Values: 2704.0 2716.2 2719.8 2745.6 2762.2 4694.4 4766.7 4767.1 4838.5 4892.9 4763.4 4776.9 4804.5 4861.2 4864.0 4329.3 4331.0 4414.2 4440.0 4520.0 4463.3 4464.3 4478.9 4502.7 4535.3 4298.1 4343.3 4378.1 4409.5 4439.0 4334.6 4344.2 4346.3 4385.2 4386.6 4356.9 4364.5 4379.9 4385.9 4411.4 4626.4 4639.5 4685.9 4701.8 4708.5 5101.8 5113.8 5117.6 5163.1 5172.4 5096.1 5137.9 5139.9 5181.8 5193.7 4681.6 4800.1 4838.9 4854.1 4912.6 5372.8 5389.1 5394.4 5396.6 5397.8 5137.8 5147.8 5178.0 5181.5 5198.3 5032.7 5113.3 5170.1 5172.7 5189.2 5129.9 5132.1 5143.6 5153.0 5179.4 4887.6 5106.7 5124.9 5125.9 5132.1 4371.6 4381.5 4415.2 4426.2 4439.3 3833.1 3846.2 3848.6 3870.1 3886.6 4287.9 4421.6 4428.8 4434.6 4475.8 5077.6 5128.6 5132.5 5151.0 5153.0 4370.4 4395.8 4397.2 4399.8 4444.5 4535.6 4617.1 4650.2 4652.7 4692.9 4818.0 4823.2 4846.7 4851.1 4890.5 5116.0 5124.8 5126.6 5130.6 5160.8 4735.8 4809.5 4825.2 4827.6 4857.1 5073.7 5088.1 5093.2 5097.7 5146.7 5155.3 5157.0 5158.4 5161.7 5195.4 4877.6 4955.1 4964.1 4989.0 4994.6 4969.0 4975.5 4980.8 5001.0 5051.0 4909.0 4915.3 4919.4 4970.0 4970.2 4889.0 4992.9 5008.8 5047.1 5053.8 4907.6 4927.1 4933.1 4936.6 4974.7 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | [#1] ext.html Cycles:180 Average:46714.12 Median:37910.31 stddev:82190.63 (216.8%) stddev-sans-first:82348.15 15:00:26 INFO - PROCESS | 26994 | Values: 561.8 594.7 607.5 608.7 614.9 28845.0 43288.8 44652.3 44675.0 44788.6 42915.1 43328.6 43332.8 43347.1 43420.1 8017.8 8129.2 8135.6 8138.5 8139.6 7944.0 8018.6 8020.5 8032.9 8040.8 8084.5 8120.8 8124.9 8128.0 8130.7 7975.0 8037.0 8047.7 8070.7 8075.5 7927.6 8014.0 8024.1 8046.3 8047.4 39711.2 40240.6 40314.6 40332.4 40376.4 3378.7 3516.2 3569.5 3608.7 3810.4 27666.7 28000.3 31485.0 32110.4 32587.7 3399.4 3401.5 3407.7 3425.3 3459.1 257910.0 258752.0 258841.0 259210.0 259866.0 34543.3 35736.0 58300.0 58801.4 58999.0 36725.8 36768.0 36935.8 36940.3 36945.0 14705.6 14846.5 14849.8 14850.9 14852.3 12209.5 12258.9 12293.0 12330.9 12340.3 8600.9 8623.2 8629.3 8691.3 8697.6 3372.1 3373.1 3374.2 3380.7 3384.8 931.5 989.3 1016.0 1038.9 1052.3 29050.4 29337.9 29506.3 30217.2 30245.5 953.5 971.3 1012.0 1048.0 1062.3 15618.4 16010.4 16061.3 16130.4 16371.9 378327.0 378559.0 382802.0 383090.0 383438.0 54518.5 55197.7 55275.2 55279.7 55466.4 1131.0 1251.1 1257.5 1266.8 1267.7 29256.9 30438.0 30466.4 30931.5 31106.0 44487.0 44798.3 45006.1 45071.8 45126.5 282776.0 283749.0 284088.0 284799.0 285175.0 24080.4 24689.8 26195.6 27242.3 27387.0 44722.6 45783.0 45842.8 46389.0 46900.1 38630.0 38872.8 39326.4 39331.8 39627.4 37862.6 37958.0 38403.0 38773.8 39147.0 38402.0 38851.2 40220.2 41746.0 43148.4 33412.8 35022.8 35228.8 35705.3 35867.0 24525.4 24556.2 24917.8 24932.4 25019.1 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | [#2] jquery.html Cycles:180 Average:24653.64 Median:387.42 stddev:63695.35 (16440.8%) stddev-sans-first:63847.98 15:00:26 INFO - PROCESS | 26994 | Values: 386.9 388.0 388.9 400.9 413.8 13462.0 13873.0 17199.6 18034.8 18065.7 17106.7 17770.6 18559.6 18565.1 18615.9 5164.0 5277.4 5321.0 5341.7 5360.2 5154.0 5195.4 5277.8 5286.5 5328.7 5260.6 5267.3 5274.4 5275.4 5275.9 5167.5 5206.5 5288.6 5300.7 5317.9 4738.2 4759.4 4765.6 4767.1 4768.3 20765.0 21113.7 21909.7 21948.1 22017.0 1950.1 1957.1 1986.2 2071.2 2087.6 13326.1 13487.6 13555.8 13750.0 14307.2 1806.3 1827.8 1863.2 1869.4 1883.8 70071.6 70486.0 71680.0 71951.6 72238.6 9903.9 10040.6 10225.3 10244.3 10457.3 14088.2 14141.6 14165.6 14166.2 14210.8 7708.4 7863.6 7969.9 8025.5 8143.1 7541.8 7621.0 7748.7 7776.8 7789.5 6407.4 6452.7 6452.8 6530.5 6561.6 1831.6 1860.5 1882.4 1883.2 1884.0 888.6 893.8 896.0 939.0 1039.1 17528.6 17619.3 17710.6 18056.5 18447.0 854.6 930.3 941.9 945.6 955.0 10291.9 10542.0 10556.4 10572.6 10641.5 374241.0 378846.0 391989.0 392071.0 392222.0 67155.7 70385.3 71374.6 74251.6 82609.6 928.5 935.9 952.7 964.0 972.6 16060.7 16096.4 16268.2 16474.6 16535.0 38097.4 38313.4 38580.8 39743.2 40220.6 53830.2 53965.2 53983.0 54358.8 54709.7 6887.4 6957.3 7218.7 7279.0 7484.0 11299.3 11929.5 11974.5 12026.8 12062.5 10033.2 10359.4 10446.8 10734.2 10784.8 10310.8 10423.8 10503.2 10522.6 10822.5 10263.8 10397.2 10416.3 10474.7 10933.1 7349.0 7459.9 7469.1 7502.0 7668.2 10402.7 10693.2 10722.7 10757.4 10838.0 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | [#3] mootools.html Cycles:180 Average:5295.43 Median:4857.50 stddev:2941.44 (60.6%) stddev-sans-first:2934.76 15:00:26 INFO - PROCESS | 26994 | Values: 1352.0 1508.0 1545.1 1682.9 1823.8 7064.9 7891.1 8113.4 8134.7 8334.6 8294.5 8301.3 8392.1 8393.3 8398.8 1950.1 2239.3 2288.1 2319.0 2319.2 2329.6 2386.4 2387.0 2389.1 2391.6 2297.9 2314.8 2323.1 2329.8 2333.0 1888.1 2134.2 2227.2 2231.1 2273.7 2275.7 2291.0 2297.5 2299.9 2301.0 8147.6 8201.1 8205.9 8209.7 8213.2 4633.1 4709.0 4723.4 4724.1 4727.1 2785.8 2836.4 2962.3 2986.9 2991.3 939.5 947.5 956.8 966.5 968.6 10477.2 10482.9 10488.2 10489.2 10503.5 4268.3 4311.9 4403.9 4422.3 4422.6 8954.6 8991.7 8993.6 8998.2 9011.4 4810.4 4810.9 4817.3 4818.1 4819.7 3981.8 4292.9 4371.2 4447.6 4468.3 3888.8 3898.7 3929.8 3969.1 3997.2 1254.3 1272.2 1273.7 1303.0 1317.2 755.8 888.5 900.6 909.9 939.8 5331.9 5536.9 5548.0 5640.9 5667.7 415.3 418.8 427.5 431.5 431.8 3507.4 3531.2 3572.9 3575.7 3660.0 9483.8 9685.4 9789.0 9798.3 9815.3 10018.7 10039.0 10044.0 10067.7 10113.0 7165.2 7629.8 7684.9 7708.9 7723.4 5508.6 5532.0 5557.7 5560.0 5648.8 9104.9 9144.4 9189.0 9271.8 9277.0 8649.4 8991.7 8994.3 9267.5 9289.5 6402.1 6614.6 6622.8 7206.2 7362.7 7250.2 7353.2 7639.6 7823.0 7846.3 7149.9 7289.5 7341.4 7376.7 7422.2 6652.9 6918.1 7136.3 7172.7 7296.4 6254.7 6467.5 6472.1 6472.3 6480.8 4728.1 4747.6 4748.8 4774.9 4825.8 4747.2 4889.2 4953.8 5019.0 5027.7 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | [#4] prototype.html Cycles:180 Average:4080.06 Median:4444.11 stddev:1167.38 (26.3%) stddev-sans-first:1135.73 15:00:26 INFO - PROCESS | 26994 | Values: 304.0 317.2 324.8 327.6 330.1 4850.2 4870.1 4893.6 4896.3 4896.8 4552.2 4600.3 4638.6 4757.7 4829.6 3776.8 3788.1 3819.2 3825.5 3857.6 3845.9 3850.3 3870.9 3871.4 3880.7 3593.8 3662.4 3744.9 3793.5 3799.2 3753.8 3760.6 3784.0 3786.7 3803.2 3369.4 3374.4 3380.7 3382.2 3385.7 4342.8 4608.1 4612.5 4619.0 4623.5 4298.5 4344.4 4350.0 4363.9 4369.7 5051.1 5054.5 5054.6 5070.2 5073.5 3570.8 3636.7 3652.4 3661.9 3668.2 5155.6 5252.3 5253.2 5256.2 5258.2 3698.2 3699.4 3699.7 3701.5 3702.4 3752.2 3755.3 3793.9 3796.8 3807.4 4011.5 4243.3 4252.9 4355.8 4356.6 5002.6 5008.1 5033.5 5054.9 5085.6 1219.1 1279.4 1294.6 1296.9 1310.0 830.9 842.4 846.5 854.8 863.8 4034.8 4038.6 4061.2 4072.0 4072.9 4628.9 4673.4 4684.6 4688.4 4707.5 3966.0 4023.5 4032.7 4082.5 4122.1 2351.9 2460.7 2477.9 2504.5 2505.7 4339.2 4358.9 4434.9 4447.5 4450.0 4715.9 4730.8 4740.3 4744.8 4792.4 4085.6 4417.4 4426.4 4436.8 4440.8 4591.5 4608.4 4635.3 4639.5 4669.5 4515.7 4577.7 4602.2 4611.2 4655.7 4362.1 4436.4 4450.6 4522.3 4981.2 3747.4 3761.3 3765.0 3780.6 3782.9 5045.8 5207.7 5208.9 5243.2 5252.9 4940.5 5046.8 5055.8 5078.8 5086.7 5021.6 5028.2 5031.6 5034.3 5080.0 4897.3 5026.3 5027.6 5029.5 5035.3 5109.3 5129.5 5129.6 5140.2 5144.4 4989.5 5022.4 5029.2 5038.2 5049.2 15:00:26 INFO - PROCESS | 26994 | 15:00:26 INFO - PROCESS | 26994 | [#5] yui.html Cycles:180 Average:13581.33 Median:49367.88 stddev:16105.56 (32.6%) stddev-sans-first:16121.86 15:00:26 INFO - PROCESS | 26994 | Values: 737.4 760.9 761.6 780.4 794.9 11272.9 11770.4 11835.9 11910.2 11912.4 13162.9 13185.7 13207.5 13220.2 13233.7 663.9 844.2 865.9 877.5 892.3 870.1 879.5 883.5 884.3 884.4 942.6 944.4 944.4 944.5 944.8 709.8 909.8 930.4 931.8 936.6 695.5 695.7 695.9 696.0 697.0 12707.4 12715.7 12732.2 12747.4 12755.7 11837.3 11856.8 12216.7 12423.9 12489.9 11084.3 11119.0 11211.2 11300.6 11595.1 4193.6 4230.8 4236.1 4239.5 4299.9 47821.3 47851.5 49288.0 49447.8 49529.3 9593.3 9849.4 9909.6 9968.2 9987.8 16780.3 17050.0 17338.5 17386.5 17524.3 6627.2 6853.1 6898.6 6936.3 6948.0 3785.3 3848.7 3912.6 3971.6 4056.3 3054.6 3170.4 3176.4 3177.4 3177.4 551.3 555.5 566.1 578.6 582.7 600.9 627.6 630.9 637.3 639.4 8258.3 8266.7 8337.4 8342.5 8486.2 558.4 593.8 610.8 613.4 623.6 3751.4 3908.2 3974.3 4055.7 4104.7 57873.4 58003.1 58766.4 59899.4 60143.0 51561.0 52337.7 52492.5 52665.7 54627.5 47657.0 47955.0 48342.5 48423.5 48453.8 7658.6 7805.1 7838.3 7851.6 8025.9 32175.4 32252.4 32331.0 33000.8 33127.5 41101.0 41972.4 42166.4 42479.2 42655.0 9840.7 9965.5 9975.8 10173.0 10517.3 11027.8 11036.7 11359.4 11528.6 11702.5 10429.7 10495.4 10546.8 10579.2 10772.4 10567.5 10669.9 10790.0 10831.1 11038.7 9771.6 10005.1 10403.6 10986.4 11179.0 9003.5 9172.6 9404.9 10077.2 10314.6 9438.9 9527.9 9551.9 9590.3 9642.4 15:00:26 INFO - PROCESS | 26994 | -------- Summary: end -------- 15:00:26 INFO - PROCESS | 26994 | 15:00:27 INFO - TEST-INFO | 26994: exit 0 15:00:27 INFO - TEST-OK | dromaeo_css | took 1118816ms 15:00:27 INFO - TEST-START | kraken 15:00:27 INFO - operating with platform_type : linux_ 15:00:27 INFO - Initialising browser for kraken test... 15:00:27 INFO - TEST-INFO | started process 27170 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2wPeJg/profile http://localhost:46748/getInfo.html) 15:00:31 INFO - PROCESS | 27170 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 15:00:31 INFO - PROCESS | 27170 | __metrics Screen width/height:1600/1200 15:00:31 INFO - PROCESS | 27170 | colorDepth:24 15:00:31 INFO - PROCESS | 27170 | Browser inner width/height: 1024/697 15:00:31 INFO - PROCESS | 27170 | __metrics 15:00:32 ERROR - PROCESS | 27170 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:00:33 INFO - TEST-INFO | 27170: exit 0 15:00:33 INFO - Browser initialized. 15:00:33 INFO - Running cycle 1/1 for kraken test... 15:00:33 INFO - TEST-INFO | started process 27249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2wPeJg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:00:45 INFO - PROCESS | 27249 | RSS: Main: 154976256 15:00:45 INFO - PROCESS | 27249 | 15:00:48 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46748/tests/kraken/driver.html?testName=audio-beat-detection) 15:00:49 INFO - PROCESS | 27249 | RSS: Main: 210436096 15:00:49 INFO - PROCESS | 27249 | 15:00:52 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46748/tests/kraken/driver.html?testName=audio-dft) 15:00:52 INFO - PROCESS | 27249 | RSS: Main: 196935680 15:00:52 INFO - PROCESS | 27249 | 15:00:56 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46748/tests/kraken/driver.html?testName=audio-fft) 15:00:56 INFO - PROCESS | 27249 | RSS: Main: 267239424 15:00:56 INFO - PROCESS | 27249 | 15:00:58 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46748/tests/kraken/driver.html?testName=audio-oscillator) 15:00:59 INFO - PROCESS | 27249 | RSS: Main: 175939584 15:00:59 INFO - PROCESS | 27249 | 15:01:01 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46748/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:01:02 INFO - PROCESS | 27249 | RSS: Main: 180396032 15:01:02 INFO - PROCESS | 27249 | 15:01:05 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46748/tests/kraken/driver.html?testName=imaging-darkroom) 15:01:06 INFO - PROCESS | 27249 | RSS: Main: 216227840 15:01:06 INFO - PROCESS | 27249 | 15:01:10 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46748/tests/kraken/driver.html?testName=imaging-desaturate) 15:01:10 INFO - PROCESS | 27249 | RSS: Main: 215117824 15:01:10 INFO - PROCESS | 27249 | 15:01:13 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46748/tests/kraken/driver.html?testName=json-parse-financial) 15:01:14 INFO - PROCESS | 27249 | RSS: Main: 215011328 15:01:14 INFO - PROCESS | 27249 | 15:01:16 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46748/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:01:16 INFO - PROCESS | 27249 | RSS: Main: 209723392 15:01:16 INFO - PROCESS | 27249 | 15:01:18 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:01:19 INFO - PROCESS | 27249 | RSS: Main: 212013056 15:01:19 INFO - PROCESS | 27249 | 15:01:21 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:01:22 INFO - PROCESS | 27249 | RSS: Main: 216117248 15:01:22 INFO - PROCESS | 27249 | 15:01:25 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:01:25 INFO - PROCESS | 27249 | RSS: Main: 220540928 15:01:25 INFO - PROCESS | 27249 | 15:01:28 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:01:29 INFO - PROCESS | 27249 | RSS: Main: 216535040 15:01:29 INFO - PROCESS | 27249 | 15:01:30 INFO - PROCESS | 27249 | Cycle 1(1): loaded http://localhost:46748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46748/tests/kraken/driver.html?testName=ai-astar) 15:01:31 INFO - PROCESS | 27249 | RSS: Main: 262844416 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | __start_tp_report 15:01:31 INFO - PROCESS | 27249 | _x_x_mozilla_page_load 15:01:31 INFO - PROCESS | 27249 | _x_x_mozilla_page_load_details 15:01:31 INFO - PROCESS | 27249 | |i|pagename|runs| 15:01:31 INFO - PROCESS | 27249 | |0;ai-astar;111;109;114;114;113;111;113;113;112;112 15:01:31 INFO - PROCESS | 27249 | |1;audio-beat-detection;161;130;151;214;130;165;160;135;178;130 15:01:31 INFO - PROCESS | 27249 | |2;audio-dft;161;162;160;162;160;163;162;163;162;161 15:01:31 INFO - PROCESS | 27249 | |3;audio-fft;76;75;77;148;75;76;77;76;124;76 15:01:31 INFO - PROCESS | 27249 | |4;audio-oscillator;93;93;148;92;94;92;127;92;93;130 15:01:31 INFO - PROCESS | 27249 | |5;imaging-gaussian-blur;116;118;117;117;118;117;117;117;116;117 15:01:31 INFO - PROCESS | 27249 | |6;imaging-darkroom;145;145;146;146;146;146;146;145;145;145 15:01:31 INFO - PROCESS | 27249 | |7;imaging-desaturate;95;94;95;95;95;94;94;95;94;95 15:01:31 INFO - PROCESS | 27249 | |8;json-parse-financial;61;63;64;62;63;62;65;63;61;62 15:01:31 INFO - PROCESS | 27249 | |9;json-stringify-tinderbox;64;64;64;63;63;63;64;64;65;64 15:01:31 INFO - PROCESS | 27249 | |10;stanford-crypto-aes;81;80;81;80;80;81;81;82;157;85 15:01:31 INFO - PROCESS | 27249 | |11;stanford-crypto-ccm;133;141;135;132;135;125;128;127;143;137 15:01:31 INFO - PROCESS | 27249 | |12;stanford-crypto-pbkdf2;169;167;165;165;166;166;166;166;166;165 15:01:31 INFO - PROCESS | 27249 | |13;stanford-crypto-sha256-iterative;67;69;66;65;65;64;65;65;65;65 15:01:31 INFO - PROCESS | 27249 | __end_tp_report 15:01:31 INFO - PROCESS | 27249 | __start_cc_report 15:01:31 INFO - PROCESS | 27249 | _x_x_mozilla_cycle_collect,2096 15:01:31 INFO - PROCESS | 27249 | __end_cc_report 15:01:31 INFO - PROCESS | 27249 | __startTimestamp1463263291245__endTimestamp 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | ------- Summary: start ------- 15:01:31 INFO - PROCESS | 27249 | Number of tests: 14 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:1.55 (1.4%) stddev-sans-first:1.58 15:01:31 INFO - PROCESS | 27249 | Values: 111.0 109.0 114.0 114.0 113.0 111.0 113.0 113.0 112.0 112.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#1] audio-beat-detection Cycles:10 Average:155.40 Median:160.50 stddev:26.79 (16.7%) stddev-sans-first:28.34 15:01:31 INFO - PROCESS | 27249 | Values: 161.0 130.0 151.0 214.0 130.0 165.0 160.0 135.0 178.0 130.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 15:01:31 INFO - PROCESS | 27249 | Values: 161.0 162.0 160.0 162.0 160.0 163.0 162.0 163.0 162.0 161.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#3] audio-fft Cycles:10 Average:88.00 Median:76.00 stddev:25.93 (34.1%) stddev-sans-first:27.14 15:01:31 INFO - PROCESS | 27249 | Values: 76.0 75.0 77.0 148.0 75.0 76.0 77.0 76.0 124.0 76.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#4] audio-oscillator Cycles:10 Average:105.40 Median:92.50 stddev:21.13 (22.8%) stddev-sans-first:21.92 15:01:31 INFO - PROCESS | 27249 | Values: 93.0 93.0 148.0 92.0 94.0 92.0 127.0 92.0 93.0 130.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 15:01:31 INFO - PROCESS | 27249 | Values: 116.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 116.0 117.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 15:01:31 INFO - PROCESS | 27249 | Values: 145.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:01:31 INFO - PROCESS | 27249 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 95.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:1.26 (2.0%) stddev-sans-first:1.20 15:01:31 INFO - PROCESS | 27249 | Values: 61.0 63.0 64.0 62.0 63.0 62.0 65.0 63.0 61.0 62.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 15:01:31 INFO - PROCESS | 27249 | Values: 64.0 64.0 64.0 63.0 63.0 63.0 64.0 64.0 65.0 64.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:81.00 stddev:24.01 (29.6%) stddev-sans-first:25.30 15:01:31 INFO - PROCESS | 27249 | Values: 81.0 80.0 81.0 80.0 80.0 81.0 81.0 82.0 157.0 85.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:135.00 stddev:5.87 (4.4%) stddev-sans-first:6.22 15:01:31 INFO - PROCESS | 27249 | Values: 133.0 141.0 135.0 132.0 135.0 125.0 128.0 127.0 143.0 137.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.00 stddev:1.20 (0.7%) stddev-sans-first:0.67 15:01:31 INFO - PROCESS | 27249 | Values: 169.0 167.0 165.0 165.0 166.0 166.0 166.0 166.0 166.0 165.0 15:01:31 INFO - PROCESS | 27249 | 15:01:31 INFO - PROCESS | 27249 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.00 stddev:1.43 (2.2%) stddev-sans-first:1.42 15:01:31 INFO - PROCESS | 27249 | Values: 67.0 69.0 66.0 65.0 65.0 64.0 65.0 65.0 65.0 65.0 15:01:31 INFO - PROCESS | 27249 | -------- Summary: end -------- 15:01:31 INFO - PROCESS | 27249 | 15:01:32 INFO - TEST-INFO | 27249: exit 0 15:01:32 INFO - TEST-OK | kraken | took 64930ms 15:01:32 INFO - SUITE-END | took 1183s 15:01:32 INFO - Completed test suite (00:19:44) 15:01:32 INFO - javascript benchmark 15:01:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2703.959440608391, 2716.1579910227824, 2719.8096133270656, 2745.5744359624255, 2762.24038389443, 4694.389729335182, 4766.690165139264, 4767.141914455401, 4838.467768545457, 4892.926606100911, 4763.404574428197, 4776.880577985552, 4804.519548045194, 4861.173600487917, 4864.027194561093, 4329.264025115722, 4331.0, 4414.249577571804, 4440.045390241097, 4519.954800452003, 4463.3081872309685, 4464.330350447426, 4478.865634030968, 4502.729836209838, 4535.274356103024, 4298.140371925596, 4343.348497725335, 4378.10248898975, 4409.4708634963845, 4438.977805110953, 4334.566543345672, 4344.196323680129, 4346.326319420495, 4385.232584297767, 4386.627136693385, 4356.89107772305, 4364.541723119059, 4379.890502737425, 4385.868423947287, 4411.360352748833, 4626.352310676508, 4639.512851150615, 4685.906281874343, 4701.835435759732, 4708.4820669726305, 5101.846944591631, 5113.846584602431, 5117.590592752571, 5163.122269214243, 5172.405173405069, 5096.108181068335, 5137.897242055175, 5139.922901156486, 5181.818636347747, 5193.714345710984, 4681.602063824579, 4800.1119792838435, 4838.879028024257, 4854.126257273725, 4912.606991440676, 5372.838814835562, 5389.110796718497, 5394.433584473652, 5396.64921780083, 5397.8110766123, 5137.8201762938115, 5147.845564633067, 5178.0, 5181.481851814789, 5198.298229738937, 5032.72320022395, 5113.258577506241, 5170.1469257572835, 5172.715500647425, 5189.247559103985, 5129.8717532061255, 5132.076226279306, 5143.639945203875, 5153.0, 5179.378474583074, 4887.608991280689, 5106.693598384109, 5124.897502049938, 5125.8718532037, 5132.101882170605, 4371.584699453547, 4381.452318460192, 4415.183191109655, 4426.22541055314, 4439.289713645866, 3833.0609000794425, 3846.211526637033, 3848.5574158972076, 3870.1485673152424, 3886.630770076838, 4287.914241715211, 4421.602055814992, 4428.822847086145, 4434.600885920283, 4475.776211189427, 5077.619178561623, 5128.589712822965, 5132.538071573502, 5150.995555866645, 5152.9742351288005, 4370.366296886934, 4395.802188901507, 4397.164538737694, 4399.84600538986, 4444.533324000933, 4535.591796738309, 4617.12274667812, 4650.162970665313, 4652.697574657636, 4692.882677933079, 4817.975910120427, 4823.2282834746275, 4846.684965477232, 4851.054044461296, 4890.535399137077, 5115.974420127875, 5124.769385377742, 5126.589872810092, 5130.640855140105, 5160.793568257228, 4735.810567577259, 4809.495003024632, 4825.155597770306, 4827.61379089679, 4857.0528746894015, 5073.695578265316, 5088.135017047185, 5093.2105523643895, 5097.6941383517105, 5146.665466744649, 5155.304033955518, 5156.9742151289, 5158.3552055993005, 5161.690298582097, 5195.376554813447, 4877.561019508261, 4955.108080545537, 4964.05682920244, 4988.975055124701, 4994.600431965361, 4968.975155124202, 4975.477574854588, 4980.750962451935, 5000.974995125001, 5051.0, 4909.042736666316, 4915.262710593297, 4919.409670839522, 4970.030843985388, 4970.229614409773, 4888.951110488992, 4992.925106123338, 5008.799648014038, 5047.091523525653, 5053.823116191063, 4907.607391408607, 4927.137750893651, 4933.112039832865, 4936.605071594336, 4974.651774375759], "value": 4718.7133594068355, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.8089849451188, 594.7472324262187, 607.5261296188975, 608.6561092982467, 614.8647297594529, 28845.0, 43288.78355608226, 44652.3302150468, 44675.0, 44788.55211447889, 42915.14169716604, 43328.56671433285, 43332.783336083274, 43347.13305733883, 43420.13159736803, 8017.839643207118, 8129.2277233663, 8135.593220338989, 8138.470999385051, 8139.593020348989, 7943.960280198591, 8018.599070046504, 8020.478668886532, 8032.879506807373, 8040.758777236663, 8084.5149291042435, 8120.75637730869, 8124.903138076344, 8127.95936020322, 8130.674773009073, 7975.042994840598, 8037.0, 8047.718329858485, 8070.717524886601, 8075.5154690718155, 7927.563983980879, 8014.0, 8024.117347091816, 8046.275835174803, 8047.356211503065, 39711.20577588432, 40240.59759402398, 40314.59685403138, 40332.39501407481, 40376.39435408471, 3378.7465940054594, 3516.191276006533, 3569.5359603251413, 3608.6932610728118, 3810.3522401191585, 27666.723332766614, 28000.29999250035, 31485.0, 32110.357792844246, 32587.6741232587, 3399.388110140174, 3401.48977653354, 3407.6762707542994, 3425.3149370125825, 3459.100633835196, 257910.0, 258752.0, 258841.0, 259210.0, 259866.0, 34543.30913381718, 35736.0, 58300.0, 58801.4119858796, 58999.0, 36725.81637091797, 36768.0, 36935.815320923226, 36940.26119477649, 36944.96802675825, 14705.55883323502, 14846.480373186887, 14849.777253341208, 14850.92574537142, 14852.331645075998, 12209.450574724158, 12258.877411225774, 12293.0, 12330.876691232972, 12340.259584424963, 8600.8709869352, 8623.223909848019, 8629.309655227567, 8691.304695624334, 8697.565121743888, 3372.072680012967, 3373.089265898243, 3374.156460884779, 3380.695737383598, 3384.7969121852766, 931.4504442379028, 989.3223142147652, 1015.9847602285971, 1038.9272750907364, 1052.347544522401, 29050.41899162005, 29337.85331073331, 29506.262343441587, 30217.244568885955, 30245.546316805267, 953.4565297780199, 971.3249291742171, 1011.9595216191417, 1047.9991608014327, 1062.3041907550567, 15618.3752649895, 16010.439634612732, 16061.277242524115, 16130.354785808437, 16371.918140409221, 378327.0, 378559.0, 382802.0, 383090.0, 383438.0, 54518.45481545213, 55197.72401138049, 55275.170872436946, 55279.72360138174, 55466.44533554694, 1130.9943450282697, 1251.0992085698283, 1257.5158563952943, 1266.7973124300252, 1267.6704056945446, 29256.853715731286, 30438.0, 30466.390672186877, 30931.536026960064, 31106.0, 44487.0, 44798.328025079, 45006.0998780016, 45071.77464112658, 45126.54873451355, 282776.0, 283749.0, 284088.0, 284799.0, 285175.0, 24080.397990050387, 24689.753102469465, 26195.607065893644, 27242.318942026606, 27387.0, 44722.55277447314, 45783.0, 45842.770786145855, 46389.0, 46900.06199876052, 38630.0, 38872.80563597277, 39326.41010384904, 39331.80334098311, 39627.405588916765, 37862.62137378591, 37958.0, 38403.0, 38773.80613096916, 39147.0, 38402.0, 38851.222975539764, 40220.19559608733, 41746.0, 43148.352774707775, 33412.83293583516, 35022.82488587541, 35228.82385588055, 35705.28589428145, 35867.0, 24525.38686532851, 24556.19527407935, 24917.750822491544, 24932.37669058288, 25019.12433064834], "value": 17355.99443278002, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.86653104678874, 387.9786611736355, 388.93582558877785, 400.89376315276473, 413.80551140963735, 13462.0, 13873.028887977845, 17199.57001074972, 18034.81965180348, 18065.729014064782, 17106.65786684269, 17770.555736106602, 18559.628807423844, 18565.071746412657, 18615.90692046538, 5163.974180129104, 5277.445868183843, 5321.042212401766, 5341.706206158679, 5360.249565060894, 5154.0, 5195.376554813409, 5277.788888444457, 5286.471352864722, 5328.706921119337, 5260.55285300748, 5267.26258323835, 5274.4198138204765, 5275.36695596529, 5275.947240527603, 5167.457416971239, 5206.479352064782, 5288.576913846902, 5300.734963251842, 5317.946820531823, 4738.241881298975, 4759.428868535763, 4765.618750499986, 4767.118083154627, 4768.260919557474, 20765.0, 21113.68329475059, 21909.671354929687, 21948.122075116975, 22017.0, 1950.0932066589105, 1957.07039156401, 1986.2154448992712, 2071.2336435518955, 2087.5929193807215, 13326.067720180616, 13487.595372138852, 13555.796663050061, 13750.0, 14307.213103279315, 1806.3406856497415, 1827.7898041725239, 1863.2174486715612, 1869.392447454569, 1883.7551118354663, 70071.64964175147, 70486.0, 71680.0, 71951.64024179845, 72238.63880680615, 9903.900960990299, 10040.598376064876, 10225.290298071017, 10244.334118282288, 10457.320274182153, 14088.15471071739, 14141.646458838612, 14165.645858853404, 14166.220857852712, 14210.786838197435, 7708.354127158332, 7863.567503787347, 7969.880451793228, 8025.478343907627, 8143.063547691972, 7541.8114547135665, 7620.961895190489, 7748.728794492278, 7776.84446311082, 7789.532628042336, 6407.391297826699, 6452.709628066748, 6452.838679033061, 6530.542861999615, 6561.639109848908, 1831.5512699388469, 1860.451166905739, 1882.4164509002253, 1883.1996401529295, 1883.9717404238947, 888.5912480259024, 893.8301722672671, 895.9776005599912, 938.9671361502315, 1039.1323245090282, 17528.561785955197, 17619.295228190727, 17710.557236069202, 18056.548586285186, 18447.0, 854.5940678177816, 930.2697382554662, 941.8634298026885, 945.5555888732422, 954.9856752148723, 10291.897081029243, 10542.0, 10556.419396933243, 10572.629957951434, 10641.521131548943, 374241.0, 378846.0, 391989.0, 392071.0, 392222.0, 67155.66422167956, 70385.2961470389, 71374.64312678507, 74251.62874185595, 82609.58695206486, 928.456852741154, 935.9064093590723, 952.7094236258008, 964.0455948610964, 972.6109556177444, 16060.67878642444, 16096.436624718079, 16268.186590670182, 16474.58813529671, 16535.033115880397, 38097.4285385725, 38313.4252986211, 38580.807095965465, 39743.20513589654, 40220.597794022855, 53830.192547111044, 53965.190522142984, 53983.0, 54358.82564697368, 54709.72645136749, 6887.449004079762, 6957.2694867038235, 7218.747343842941, 7279.017332660023, 7484.0, 11299.265547739371, 11929.46317415701, 11974.521019159136, 12026.819597705866, 12062.51749929993, 10033.1973442123, 10359.430231337348, 10446.843297350393, 10734.194935380034, 10784.784304314027, 10310.793784124426, 10423.791524169626, 10503.159747220052, 10522.579096836042, 10822.458877056273, 10263.84604230952, 10397.16822654201, 10416.32293900894, 10474.68575942723, 10933.125349971824, 7348.96325518369, 7459.925400745923, 7469.1037075551285, 7502.0, 7668.194839541991, 10402.687919362432, 10693.198010149114, 10722.678319650424, 10757.408342541235, 10838.0], "value": 8588.386352496309, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.0, 1507.9623009424765, 1545.1424459425018, 1682.8653707703377, 1823.8449731772798, 7064.894026589599, 7891.053073631165, 8113.350931925446, 8134.715284965027, 8334.624941877615, 8294.502329860212, 8301.252887240147, 8392.118827523114, 8393.328533717304, 8398.832023359544, 1950.0834607734414, 2239.32820153954, 2288.096202000209, 2318.976810231893, 2319.1535089692225, 2329.592321343758, 2386.427257458215, 2386.976130238693, 2389.1279682915697, 2391.629297458897, 2297.908083676651, 2314.8148148148193, 2323.0939933425975, 2329.8369114161933, 2333.0, 1888.0748433267738, 2134.2423439678846, 2227.175944900381, 2231.0518029837335, 2273.6816845641542, 2275.7382900966436, 2291.0377641390655, 2297.4945511987335, 2299.8850057497225, 2300.9654855177187, 8147.551884646342, 8201.056878458994, 8205.917940820635, 8209.71266005693, 8213.178682131798, 4633.096546173498, 4709.034647897173, 4723.385959825201, 4724.078804633098, 4727.101850648366, 2785.832850029005, 2836.375997280595, 2962.259435141215, 2986.8655910483862, 2991.341904780945, 939.5208443693697, 947.5215016416735, 956.7942892278124, 966.4781018250136, 968.6077138758743, 10477.214208934283, 10482.895171048269, 10488.160947124212, 10489.160867130611, 10503.52734126966, 4268.317069268902, 4311.892202694958, 4403.911921761547, 4422.336649502536, 4422.557744225616, 8954.641814327353, 8991.685291014783, 8993.595288212045, 8998.235150012322, 9011.369204155777, 4810.4467986182035, 4810.879728006757, 4817.301491283744, 4818.132736107465, 4819.662623616313, 3981.7810020449156, 4292.93560596585, 4371.169477799272, 4447.577480139382, 4468.262736648481, 3888.766673999569, 3898.6686131679126, 3929.76421414716, 3969.0871099647243, 3997.2405243003736, 1254.3414707278753, 1272.1667307913146, 1273.6943133647858, 1302.9674258143623, 1317.1767645221737, 755.8223817402906, 888.5113187746714, 900.5632268349846, 909.8726178335038, 939.8120375924842, 5331.86670333237, 5536.86157846049, 5548.029094908375, 5640.8871822563315, 5667.716614169358, 415.3209502463434, 418.84293389978757, 427.53398795313257, 431.4822213344, 431.82079437033906, 3507.3511400391008, 3531.205478767257, 3572.8570857165423, 3575.7318201135017, 3660.0, 9483.810323793485, 9685.41887486753, 9788.951055244679, 9798.31411801167, 9815.263855210887, 10018.749531261778, 10038.996100389902, 10044.0, 10067.69796906094, 10113.0, 7165.211826699059, 7629.847403052019, 7684.884726729104, 7708.9229107709325, 7723.382129429632, 5508.586855985737, 5531.9723401382735, 5557.722113894209, 5560.0, 5648.774049037993, 9104.908950910407, 9144.405613635092, 9189.0, 9271.76820579491, 9277.0, 8649.394542382224, 8991.685291014783, 8994.280457563511, 9267.53662316895, 9289.535523223947, 6402.103705481143, 6614.6361950093205, 6622.8344291393105, 7206.207317195209, 7362.705491780269, 7250.238724933806, 7353.154387245535, 7639.618019099134, 7823.0, 7846.254605812439, 7149.857002860018, 7289.45329100309, 7341.449391295568, 7376.668049937873, 7422.1835598085245, 6652.933470665232, 6918.066061081689, 7136.250693677089, 7172.748953786592, 7296.379807716404, 6254.718537665907, 6467.450266727381, 6472.093906853139, 6472.288048314587, 6480.773172938924, 4728.148933192058, 4747.620190384692, 4748.833790817304, 4774.928376074292, 4825.806967721252, 4747.216709242936, 4889.188043776417, 4953.7523123844385, 5019.02129084825, 5027.698338099725], "value": 4210.904730691454, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.99848000759977, 317.18325312320775, 324.82134825845793, 327.63468732363424, 330.10541432717366, 4850.175470170071, 4870.147724148278, 4893.632977526682, 4896.314515967759, 4896.804127834882, 4552.226121559334, 4600.263957766758, 4638.582527572517, 4757.738324392157, 4829.565339119478, 3776.7733935963793, 3788.109794198375, 3819.197968426634, 3825.5409350877935, 3857.556381016191, 3845.903852403684, 3850.345441274976, 3870.9419358709642, 3871.399933010388, 3880.6895448364203, 3593.8203089845533, 3662.414013757786, 3744.868929587465, 3793.468914351993, 3799.164183879542, 3753.8123093845197, 3760.6051364606733, 3783.981080094582, 3786.678132358753, 3803.163304073127, 3369.444041733111, 3374.3926093303194, 3380.7126394256516, 3382.2051817822794, 3385.6783605557434, 4342.761148136851, 4608.124456353282, 4612.492625811158, 4618.95381046192, 4623.491415944277, 4298.484181898192, 4344.413504176927, 4349.956500434987, 4363.890902727426, 4369.694121411502, 5051.1413059779925, 5054.519820617035, 5054.620903432257, 5070.188769796815, 5073.46728593496, 3570.7875476138224, 3636.654517820803, 3652.397354436515, 3661.8718344858066, 3668.211334563055, 5155.587552995751, 5252.290940722992, 5253.213907271508, 5256.237845512379, 5258.1849813279005, 3698.20488594948, 3699.445083237482, 3699.687695190506, 3701.5193922431245, 3702.389105797567, 3752.1932784451474, 3755.267722794029, 3793.886183414502, 3796.7911764853197, 3807.428885667117, 4011.478507793968, 4243.278642630698, 4252.914941701149, 4355.847545335898, 4356.586124318184, 5002.59979201663, 5008.12357837377, 5033.496650335011, 5054.898902021939, 5085.567726743194, 1219.1405059433077, 1279.3731071774764, 1294.6245588779148, 1296.935153242334, 1309.9520383693007, 830.9364014062011, 842.3598065470287, 846.5132548784351, 854.8162145138825, 863.7883718488885, 4034.789563131049, 4038.63652271297, 4061.1877624475223, 4071.9796401017807, 4072.9389059164137, 4628.884277893079, 4673.415823022122, 4684.648651351163, 4688.413948256468, 4707.458642256184, 3966.0, 4023.4970628671417, 4032.697547683935, 4082.510098788164, 4122.113745544662, 2351.8824058797336, 2460.7047154341235, 2477.8884950177285, 2504.5491811473744, 2505.6993160820816, 4339.240632889295, 4358.912821743547, 4434.866953991385, 4447.466304043535, 4449.977750111293, 4715.929261061086, 4730.763461826895, 4740.265258884879, 4744.833930812474, 4792.353032340659, 4085.611866872642, 4417.381566580745, 4426.380306757057, 4436.822527098944, 4440.756588155322, 4591.4949355571525, 4608.3778689876435, 4635.281531362644, 4639.5360463954685, 4669.48635650085, 4515.729056256635, 4577.748223847587, 4602.217623004164, 4611.201631363838, 4655.674102812771, 4362.062156636339, 4436.378906953094, 4450.553570089798, 4522.276435770261, 4981.2030075187795, 3747.3816820225447, 3761.3417651911354, 3765.0, 3780.6030366811087, 3782.867599634, 5045.798168073383, 5207.739613019258, 5208.895822083613, 5243.213517972335, 5252.921206181833, 4940.456549779593, 5046.823361182341, 5055.823046193365, 5078.796848126033, 5086.669366491166, 5021.598272138147, 5028.195488721787, 5031.597472202142, 5034.263179203169, 5079.984003199449, 4897.289892965571, 5026.271190677405, 5027.59227416324, 5029.535527591527, 5035.345405097314, 5109.259157422079, 5129.538341549279, 5129.640925135204, 5140.177571588528, 5144.382674079135, 4989.525995030466, 5022.447530771539, 5029.1953287473825, 5038.244263360379, 5049.182294374096], "value": 3718.0258370944216, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.4137560639291, 760.8592410404076, 761.5735188294556, 780.4146889832625, 794.8529522038423, 11272.943635281823, 11770.411479426037, 11835.881641183585, 11910.22583532072, 11912.404379781008, 13162.93418532906, 13185.670358241048, 13207.537736179236, 13220.206787592751, 13233.669158271048, 663.9203295604534, 844.1853611265128, 865.9307255419551, 877.5261358866204, 892.2683399612321, 870.1124852650292, 879.4855009819263, 883.5493898111977, 884.3102380143498, 884.3765145572356, 942.6417961174744, 944.4286206844863, 944.4427787605312, 944.5418971798672, 944.8063147054871, 709.8048036789872, 909.8316811389848, 930.3952430919888, 931.753085432361, 936.5972631768337, 695.4922906278445, 695.6799872058859, 695.9095317608678, 696.0116634379194, 696.9965150174216, 12707.364631768374, 12715.682107947283, 12732.17240879349, 12747.36263186837, 12755.744885102338, 11837.348945807977, 11856.762864742743, 12216.694582635417, 12423.93788031063, 12489.875101248963, 11084.334939903632, 11119.0, 11211.15916306272, 11300.604478843283, 11595.072394208442, 4193.622573968358, 4230.78846057699, 4236.0892408132095, 4239.512456067561, 4299.871003869889, 47821.28268075982, 47851.52148478505, 49288.0, 49447.75276123633, 49529.25706114411, 9593.328467007383, 9849.359791613522, 9909.603615855429, 9968.152707019839, 9987.750306242402, 16780.328786848633, 17050.0, 17338.479845604652, 17386.47840564785, 17524.29902803899, 6627.204735431683, 6853.143357080365, 6898.586084834926, 6936.341047600571, 6948.0, 3785.280796648628, 3848.6536211741077, 3912.6478616924614, 3971.6028397160053, 4056.290149223874, 3054.57235986962, 3170.3659268146002, 3176.38060578185, 3177.396294703999, 3177.428062948645, 551.3273805956726, 555.5305766627193, 566.0772940107598, 578.5660754434174, 582.6620560075146, 600.8828278485653, 627.6234259444296, 630.9337519560472, 637.3053371824733, 639.4404895716249, 8258.339332853357, 8266.669333226724, 8337.416380853403, 8342.499450032896, 8486.236238738544, 558.4276116980128, 593.8248216776061, 610.8075956073823, 613.414189449075, 623.5790841182184, 3751.3810221313724, 3908.218356328746, 3974.3442332014893, 4055.6755459563756, 4104.671626269891, 57873.42126578765, 58003.12995305074, 58766.41233587695, 59899.401005989384, 60143.0, 51561.0, 52337.7383113082, 52492.475075249524, 52665.7366713164, 54627.453725463034, 47657.0, 47954.9999999993, 48342.5165748345, 48423.5157648426, 48453.75773121112, 7658.578778167254, 7805.102413222325, 7838.294553490213, 7851.646675899484, 8025.8796118059445, 32175.356492869545, 32252.35495290128, 32331.0, 33000.834995824865, 33127.50308745419, 41101.0, 41972.37041444442, 42166.36750448684, 42479.150416990866, 42655.0, 9840.65557705477, 9965.451900145561, 9975.800483990424, 10173.0, 10517.316374435637, 11027.779444411228, 11036.724081898017, 11359.432028398713, 11528.59649912249, 11702.531898724295, 10429.739256518344, 10495.422751748727, 10546.789064218827, 10579.15366770641, 10772.407517586607, 10567.471626418803, 10669.8933010672, 10790.0, 10831.133509319394, 11038.668839934815, 9771.609135634495, 10005.149562287286, 10403.635872744419, 10986.395748233925, 11179.049780768726, 9003.459792412476, 9172.587233574372, 9404.858927116236, 10077.244206684674, 10314.587416503557, 9438.905610943802, 9527.857082143912, 9551.904480955101, 9590.280728945496, 9642.373245739005], "value": 6217.905289525639, "unit": "score"}], "name": "dromaeo_css", "value": 6396.28443295731, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 114.0, 114.0, 113.0, 111.0, 113.0, 113.0, 112.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 130.0, 151.0, 214.0, 130.0, 165.0, 160.0, 135.0, 178.0, 130.0], "value": 155.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 162.0, 160.0, 163.0, 162.0, 163.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 148.0, 75.0, 76.0, 77.0, 76.0, 124.0, 76.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 148.0, 92.0, 94.0, 92.0, 127.0, 92.0, 93.0, 130.0], "value": 105.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 64.0, 62.0, 63.0, 62.0, 65.0, 63.0, 61.0, 62.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 63.0, 63.0, 64.0, 64.0, 65.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 80.0, 80.0, 81.0, 81.0, 82.0, 157.0, 85.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 141.0, 135.0, 132.0, 135.0, 125.0, 128.0, 127.0, 143.0, 137.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 165.0, 165.0, 166.0, 166.0, 166.0, 166.0, 166.0, 165.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1560.1999999999996, "alertThreshold": 2.0}]} 15:01:32 INFO - Return code: 0 15:01:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:01:33 ERROR - # TBPL SUCCESS # 15:01:33 INFO - Running post-action listener: _resource_record_post_action 15:01:33 INFO - ##### 15:01:33 INFO - ##### Finished run-tests step (success) 15:01:33 INFO - ##### 15:01:33 INFO - Running post-run listener: _resource_record_post_run 15:01:33 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 8192; Write bytes: 531771392; Read time: 400; Write time: 464692 15:01:33 INFO - TinderboxPrint: CPU usage
13.2% 15:01:33 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 400 15:01:33 INFO - TinderboxPrint: I/O write bytes / time
531,771,392 / 464,692 15:01:33 INFO - TinderboxPrint: CPU idle
8,320.1 (86.8%) 15:01:33 INFO - TinderboxPrint: CPU user
1,220.4 (12.7%) 15:01:33 INFO - TinderboxPrint: Swap in / out
0 / 0 15:01:33 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 236232704; Read time: 244; Write time: 285628 15:01:33 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295538688; Read time: 156; Write time: 179064 15:01:33 INFO - Running post-run listener: _upload_blobber_files 15:01:33 INFO - Blob upload gear active. 15:01:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:01:33 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 15:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:01:34 INFO - (blobuploader) - INFO - Open directory for files ... 15:01:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:01:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:01:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:01:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:01:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:01:34 INFO - (blobuploader) - INFO - Done attempting. 15:01:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:01:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:01:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:01:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:01:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:01:35 INFO - (blobuploader) - INFO - Done attempting. 15:01:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:01:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:01:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:01:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:01:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:01:39 INFO - (blobuploader) - INFO - Done attempting. 15:01:39 INFO - (blobuploader) - INFO - Iteration through files over. 15:01:39 INFO - Return code: 0 15:01:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:01:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:01:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed36c316ab8dc3bcc15446b8a5f7e2d4068e80cf4ac78345d4a0e35111093da2b397d7a3ecfbda4d0702c50a47e1bf75c18c6e40416a1ea2dc1adba6d532b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0923eb924732a41f3a2156ebce762082f51ba6188f5bf6ad766863c65a29f6231095a76a73da01dfd732f199d5edbb262b950f6866555901d4134f5bf3820215", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77556539c62e45abdfb076cae6b9dd160d41f065f4f5cf240cdd7d7822dd24c107a757ea9050cc425c1db65db7de5db7847dc80465523feac68bfbc7ed7880b7"} 15:01:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:01:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:01:39 INFO - Contents: 15:01:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed36c316ab8dc3bcc15446b8a5f7e2d4068e80cf4ac78345d4a0e35111093da2b397d7a3ecfbda4d0702c50a47e1bf75c18c6e40416a1ea2dc1adba6d532b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0923eb924732a41f3a2156ebce762082f51ba6188f5bf6ad766863c65a29f6231095a76a73da01dfd732f199d5edbb262b950f6866555901d4134f5bf3820215", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77556539c62e45abdfb076cae6b9dd160d41f065f4f5cf240cdd7d7822dd24c107a757ea9050cc425c1db65db7de5db7847dc80465523feac68bfbc7ed7880b7"} 15:01:39 INFO - Running post-run listener: copy_logs_to_upload_dir 15:01:39 INFO - Copying logs to upload dir... 15:01:39 INFO - mkdir: /builds/slave/test/build/upload/logs 15:01:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.272940 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-14 15:01:38.255779) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 15:01:38.260196) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed36c316ab8dc3bcc15446b8a5f7e2d4068e80cf4ac78345d4a0e35111093da2b397d7a3ecfbda4d0702c50a47e1bf75c18c6e40416a1ea2dc1adba6d532b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0923eb924732a41f3a2156ebce762082f51ba6188f5bf6ad766863c65a29f6231095a76a73da01dfd732f199d5edbb262b950f6866555901d4134f5bf3820215", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77556539c62e45abdfb076cae6b9dd160d41f065f4f5cf240cdd7d7822dd24c107a757ea9050cc425c1db65db7de5db7847dc80465523feac68bfbc7ed7880b7"} build_url:https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011149 build_url: 'https://queue.taskcluster.net/v1/task/UAXmI3VEQA6LyDbc6-lcvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ed36c316ab8dc3bcc15446b8a5f7e2d4068e80cf4ac78345d4a0e35111093da2b397d7a3ecfbda4d0702c50a47e1bf75c18c6e40416a1ea2dc1adba6d532b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0923eb924732a41f3a2156ebce762082f51ba6188f5bf6ad766863c65a29f6231095a76a73da01dfd732f199d5edbb262b950f6866555901d4134f5bf3820215", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77556539c62e45abdfb076cae6b9dd160d41f065f4f5cf240cdd7d7822dd24c107a757ea9050cc425c1db65db7de5db7847dc80465523feac68bfbc7ed7880b7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 15:01:38.287766) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:01:38.288148) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463235864.793895-1501844012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:01:38.372303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 15:01:38.372641) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 15:01:38.372988) ========= ========= Total master_lag: 0.19 =========