builder: larch_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1464408257.77 results: success (0) buildid: 20160527183007 builduid: 470d12ba9aba4cda86b95b05deeb1e75 revision: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:17.769701) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:17.770352) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:17.770638) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:17.809634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:17.809889) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 21:04:19-- 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% 229M=0s 2016-05-27 21:04:19 (229 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.192372 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:18.016162) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:18.016455) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014845 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:18.064886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 21:04:18.065196) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a --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=82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 21:04:19,526 truncating revision to first 12 chars 2016-05-27 21:04:19,526 Setting DEBUG logging. 2016-05-27 21:04:19,526 attempt 1/10 2016-05-27 21:04:19,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/39dcfacc4b5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 21:04:20,959 unpacking tar archive at: larch-39dcfacc4b5b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.787592 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 21:04:19.866318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:19.866669) ========= script_repo_revision: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:19.867079) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:19.867361) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 21:04:19.875465) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-27 21:04:19.875749) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] 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=82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:04:21 INFO - MultiFileLogger online at 20160527 21:04:21 in /builds/slave/test 21:04:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 21:04:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:04:21 INFO - {'append_to_log': False, 21:04:21 INFO - 'base_work_dir': '/builds/slave/test', 21:04:21 INFO - 'blob_upload_branch': 'Larch', 21:04:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:04:21 INFO - 'branch': 'Larch', 21:04:21 INFO - 'buildbot_json_path': 'buildprops.json', 21:04:21 INFO - 'config_files': ('talos/linux_config.py',), 21:04:21 INFO - 'default_actions': ('clobber', 21:04:21 INFO - 'read-buildbot-config', 21:04:21 INFO - 'download-and-extract', 21:04:21 INFO - 'populate-webroot', 21:04:21 INFO - 'create-virtualenv', 21:04:21 INFO - 'install', 21:04:21 INFO - 'run-tests'), 21:04:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:04:21 INFO - 'download_minidump_stackwalk': True, 21:04:21 INFO - 'download_symbols': 'ondemand', 21:04:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:04:21 INFO - 'tooltool.py': '/tools/tooltool.py', 21:04:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:04:21 INFO - '/tools/misc-python/virtualenv.py')}, 21:04:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:04:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:04:21 INFO - 'installer_path': 'installer.exe', 21:04:21 INFO - 'log_level': 'info', 21:04:21 INFO - 'log_name': 'talos', 21:04:21 INFO - 'log_to_console': True, 21:04:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:04:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:04:21 INFO - 'opt_config_files': (), 21:04:21 INFO - 'pip_index': False, 21:04:21 INFO - 'sps_profile': False, 21:04:21 INFO - 'sps_profile_interval': 0, 21:04:21 INFO - 'suite': 'dromaeojs', 21:04:21 INFO - 'system_bits': '32', 21:04:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:04:21 INFO - 'title': 'talos-linux64-ix-066', 21:04:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:04:21 INFO - 'use_talos_json': True, 21:04:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:04:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:04:21 INFO - 'work_dir': 'build'} 21:04:21 INFO - ##### 21:04:21 INFO - ##### Running clobber step. 21:04:21 INFO - ##### 21:04:21 INFO - Running pre-action listener: _resource_record_pre_action 21:04:21 INFO - Running main action method: clobber 21:04:21 INFO - rmtree: /builds/slave/test/build 21:04:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:04:21 INFO - Running post-action listener: _resource_record_post_action 21:04:21 INFO - ##### 21:04:21 INFO - ##### Finished clobber step (success) 21:04:21 INFO - ##### 21:04:21 INFO - ##### 21:04:21 INFO - ##### Running read-buildbot-config step. 21:04:21 INFO - ##### 21:04:21 INFO - Running pre-action listener: _resource_record_pre_action 21:04:21 INFO - Running main action method: read_buildbot_config 21:04:21 INFO - Using buildbot properties: 21:04:21 INFO - { 21:04:21 INFO - "project": "", 21:04:21 INFO - "product": "firefox", 21:04:21 INFO - "script_repo_revision": "production", 21:04:21 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 21:04:21 INFO - "repository": "", 21:04:21 INFO - "buildername": "Ubuntu HW 12.04 x64 larch pgo talos dromaeojs", 21:04:21 INFO - "buildid": "20160527183007", 21:04:21 INFO - "pgo_build": "True", 21:04:21 INFO - "basedir": "/builds/slave/test", 21:04:21 INFO - "buildnumber": 4, 21:04:21 INFO - "slavename": "talos-linux64-ix-066", 21:04:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:04:21 INFO - "platform": "ubuntu64_hw", 21:04:21 INFO - "scheduler": "tests-larch-linux64-pgo-talos", 21:04:21 INFO - "branch": "larch", 21:04:21 INFO - "repo_path": "projects/larch", 21:04:21 INFO - "revision": "39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a", 21:04:21 INFO - "stage_platform": "linux64-pgo", 21:04:21 INFO - "builduid": "470d12ba9aba4cda86b95b05deeb1e75", 21:04:21 INFO - "slavebuilddir": "test" 21:04:21 INFO - } 21:04:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:04:21 INFO - Running post-action listener: _resource_record_post_action 21:04:21 INFO - ##### 21:04:21 INFO - ##### Finished read-buildbot-config step (success) 21:04:21 INFO - ##### 21:04:21 INFO - ##### 21:04:21 INFO - ##### Running download-and-extract step. 21:04:21 INFO - ##### 21:04:21 INFO - Running pre-action listener: _resource_record_pre_action 21:04:21 INFO - Running main action method: download_and_extract 21:04:21 INFO - mkdir: /builds/slave/test/build/tests 21:04: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')]} 21:04:21 INFO - https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:04:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:04:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:04:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:04:29 INFO - Downloaded 1361 bytes. 21:04:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:04:29 INFO - Using the following test package requirements: 21:04:29 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:04:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:04:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:04:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'jsshell-linux-x86_64.zip'], 21:04:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:04:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:04:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:04:29 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:04:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:04:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:04:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:04:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:29 INFO - https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:04:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:04:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:04:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:04:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:04:34 INFO - Downloaded 24420677 bytes. 21:04:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:04:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:04:35 INFO - Return code: 0 21:04:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:35 INFO - https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:04:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:04:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:04:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:04:44 INFO - Downloaded 24420677 bytes. 21:04:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:04:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:04:45 INFO - Return code: 0 21:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:45 INFO - https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:04:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:04:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:04:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:04:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:04:46 INFO - Downloaded 10834042 bytes. 21:04:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:04:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:04:47 INFO - Return code: 0 21:04: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')]} 21:04:47 INFO - https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:04:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:04:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:04:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:04:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:04:48 INFO - Downloaded 58837513 bytes. 21:04:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:04:48 INFO - mkdir: /builds/slave/test/properties 21:04:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:04:48 INFO - Writing to file /builds/slave/test/properties/build_url 21:04:48 INFO - Contents: 21:04:48 INFO - build_url:https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:04:49 INFO - Running post-action listener: _resource_record_post_action 21:04:49 INFO - Running post-action listener: set_extra_try_arguments 21:04:49 INFO - ##### 21:04:49 INFO - ##### Finished download-and-extract step (success) 21:04:49 INFO - ##### 21:04:49 INFO - ##### 21:04:49 INFO - ##### Running populate-webroot step. 21:04:49 INFO - ##### 21:04:49 INFO - Running pre-action listener: _resource_record_pre_action 21:04:49 INFO - Running main action method: populate_webroot 21:04:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:04:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:04:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:04:49 INFO - '--tppagecycles', 21:04:49 INFO - '7'], 21:04:49 INFO - 'tests': ['tsvgm']}}, 21:04:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:04:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:04:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:04:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:04:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:04:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:04:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['damp', 'tps']}, 21:04:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'tests': ['damp', 'tps']}, 21:04:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['dromaeo_dom']}, 21:04:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:04:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['a11yr', 21:04:49 INFO - 'ts_paint', 21:04:49 INFO - 'tpaint', 21:04:49 INFO - 'sessionrestore', 21:04:49 INFO - 'sessionrestore_no_auto_restore', 21:04:49 INFO - 'tabpaint']}, 21:04:49 INFO - 'other-e10s': {'tests': ['a11yr', 21:04:49 INFO - 'ts_paint', 21:04:49 INFO - 'tpaint', 21:04:49 INFO - 'sessionrestore', 21:04:49 INFO - 'sessionrestore_no_auto_restore', 21:04:49 INFO - 'tabpaint']}, 21:04:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:04:49 INFO - 'ts_paint', 21:04:49 INFO - 'tpaint', 21:04:49 INFO - 'sessionrestore', 21:04:49 INFO - 'sessionrestore_no_auto_restore', 21:04:49 INFO - 'tabpaint']}, 21:04:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:04:49 INFO - 'ts_paint', 21:04:49 INFO - 'tpaint', 21:04:49 INFO - 'sessionrestore', 21:04:49 INFO - 'sessionrestore_no_auto_restore', 21:04:49 INFO - 'tabpaint']}, 21:04:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['a11yr', 21:04:49 INFO - 'ts_paint', 21:04:49 INFO - 'tpaint', 21:04:49 INFO - 'sessionrestore', 21:04:49 INFO - 'sessionrestore_no_auto_restore', 21:04:49 INFO - 'tabpaint']}, 21:04:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['a11yr', 21:04:49 INFO - 'ts_paint', 21:04:49 INFO - 'tpaint', 21:04:49 INFO - 'sessionrestore', 21:04:49 INFO - 'sessionrestore_no_auto_restore', 21:04:49 INFO - 'tabpaint']}, 21:04:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['tsvgx', 21:04:49 INFO - 'tsvgr_opacity', 21:04:49 INFO - 'tart', 21:04:49 INFO - 'tscrollx', 21:04:49 INFO - 'cart']}, 21:04:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:04:49 INFO - 'tsvgr_opacity', 21:04:49 INFO - 'tart', 21:04:49 INFO - 'tscrollx', 21:04:49 INFO - 'cart']}, 21:04:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'talos_options': ['--disable-e10s'], 21:04:49 INFO - 'tests': ['tp5o']}, 21:04:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'tests': ['tp5o']}, 21:04:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'talos_options': ['--disable-e10s', 21:04:49 INFO - '--xperf_path', 21:04:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:04:49 INFO - 'tests': ['tp5n']}, 21:04:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:04:49 INFO - 'talos_options': ['--xperf_path', 21:04:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:04:49 INFO - 'tests': ['tp5n']}}, 21:04:49 INFO - 'talos.zip': {'path': '', 21:04:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:04:49 INFO - Running post-action listener: _resource_record_post_action 21:04:49 INFO - ##### 21:04:49 INFO - ##### Finished populate-webroot step (success) 21:04:49 INFO - ##### 21:04:49 INFO - ##### 21:04:49 INFO - ##### Running create-virtualenv step. 21:04:49 INFO - ##### 21:04:49 INFO - Running pre-action listener: _resource_record_pre_action 21:04:49 INFO - Running main action method: create_virtualenv 21:04:49 INFO - Creating virtualenv /builds/slave/test/build/venv 21:04:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:04:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:04:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:04:49 INFO - Using real prefix '/usr' 21:04:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:04:50 INFO - Installing distribute.............................................................................................................................................................................................done. 21:04:52 INFO - Installing pip.................done. 21:04:52 INFO - Return code: 0 21:04:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:04:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:04:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:04:52 INFO - Using env: {'DISPLAY': ':0', 21:04:52 INFO - 'HOME': '/home/cltbld', 21:04:52 INFO - 'LANG': 'en_US.UTF-8', 21:04:52 INFO - 'LANGUAGE': 'en_US:en', 21:04:52 INFO - 'LOGNAME': 'cltbld', 21:04:52 INFO - 'MAIL': '/var/mail/cltbld', 21:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:52 INFO - 'MOZ_NO_REMOTE': '1', 21:04:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:52 INFO - 'NO_EM_RESTART': '1', 21:04:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:52 INFO - 'PWD': '/builds/slave/test', 21:04:52 INFO - 'SHELL': '/bin/bash', 21:04:52 INFO - 'SHLVL': '1', 21:04:52 INFO - 'TERM': 'linux', 21:04:52 INFO - 'TMOUT': '86400', 21:04:52 INFO - 'USER': 'cltbld', 21:04:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:52 INFO - '_': '/tools/buildbot/bin/python'} 21:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:52 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 21:04:52 INFO - Cleaning up... 21:04:52 INFO - Return code: 0 21:04:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:04:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:04:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:04:52 INFO - Using env: {'DISPLAY': ':0', 21:04:52 INFO - 'HOME': '/home/cltbld', 21:04:52 INFO - 'LANG': 'en_US.UTF-8', 21:04:52 INFO - 'LANGUAGE': 'en_US:en', 21:04:52 INFO - 'LOGNAME': 'cltbld', 21:04:52 INFO - 'MAIL': '/var/mail/cltbld', 21:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:52 INFO - 'MOZ_NO_REMOTE': '1', 21:04:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:52 INFO - 'NO_EM_RESTART': '1', 21:04:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:52 INFO - 'PWD': '/builds/slave/test', 21:04:52 INFO - 'SHELL': '/bin/bash', 21:04:52 INFO - 'SHLVL': '1', 21:04:52 INFO - 'TERM': 'linux', 21:04:52 INFO - 'TMOUT': '86400', 21:04:52 INFO - 'USER': 'cltbld', 21:04:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:52 INFO - '_': '/tools/buildbot/bin/python'} 21:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:52 INFO - Downloading/unpacking psutil>=3.1.1 21:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:04:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:04:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:04:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:04:54 INFO - Installing collected packages: psutil 21:04:54 INFO - Running setup.py install for psutil 21:04:54 INFO - building 'psutil._psutil_linux' extension 21:04:54 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 21:04:54 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 21:04:55 INFO - building 'psutil._psutil_posix' extension 21:04:55 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 21:04:55 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 21:04:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:04:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:04:55 INFO - Successfully installed psutil 21:04:55 INFO - Cleaning up... 21:04:55 INFO - Return code: 0 21:04:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:04: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')]} 21:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04: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')]} 21:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:55 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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:04:55 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 21:04:55 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 21:04:55 INFO - Using env: {'DISPLAY': ':0', 21:04:55 INFO - 'HOME': '/home/cltbld', 21:04:55 INFO - 'LANG': 'en_US.UTF-8', 21:04:55 INFO - 'LANGUAGE': 'en_US:en', 21:04:55 INFO - 'LOGNAME': 'cltbld', 21:04:55 INFO - 'MAIL': '/var/mail/cltbld', 21:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:55 INFO - 'MOZ_NO_REMOTE': '1', 21:04:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:55 INFO - 'NO_EM_RESTART': '1', 21:04:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:55 INFO - 'PWD': '/builds/slave/test', 21:04:55 INFO - 'SHELL': '/bin/bash', 21:04:55 INFO - 'SHLVL': '1', 21:04:55 INFO - 'TERM': 'linux', 21:04:55 INFO - 'TMOUT': '86400', 21:04:55 INFO - 'USER': 'cltbld', 21:04:55 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:55 INFO - '_': '/tools/buildbot/bin/python'} 21:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:04:55 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 21:04:55 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 21:04:55 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 21:04:55 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 21:04:55 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 21:04:55 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 21:04:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:04:57 INFO - Installing collected packages: mozsystemmonitor 21:04:57 INFO - Running setup.py install for mozsystemmonitor 21:04:57 INFO - Successfully installed mozsystemmonitor 21:04:57 INFO - Cleaning up... 21:04:57 INFO - Return code: 0 21:04:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:57 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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:04:57 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 21:04:57 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 21:04:57 INFO - Using env: {'DISPLAY': ':0', 21:04:57 INFO - 'HOME': '/home/cltbld', 21:04:57 INFO - 'LANG': 'en_US.UTF-8', 21:04:57 INFO - 'LANGUAGE': 'en_US:en', 21:04:57 INFO - 'LOGNAME': 'cltbld', 21:04:57 INFO - 'MAIL': '/var/mail/cltbld', 21:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:57 INFO - 'MOZ_NO_REMOTE': '1', 21:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:57 INFO - 'NO_EM_RESTART': '1', 21:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:57 INFO - 'PWD': '/builds/slave/test', 21:04:57 INFO - 'SHELL': '/bin/bash', 21:04:57 INFO - 'SHLVL': '1', 21:04:57 INFO - 'TERM': 'linux', 21:04:57 INFO - 'TMOUT': '86400', 21:04:57 INFO - 'USER': 'cltbld', 21:04:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:57 INFO - '_': '/tools/buildbot/bin/python'} 21:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:57 INFO - Downloading/unpacking blobuploader==1.2.4 21:04:57 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 21:04:57 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 21:04:57 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 21:04:57 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 21:04:57 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 21:04:57 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 21:04:59 INFO - Downloading blobuploader-1.2.4.tar.gz 21:04:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:04:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:04:59 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 21:04:59 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 21:04:59 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 21:04:59 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 21:04:59 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 21:04:59 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 21:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:05:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:00 INFO - Downloading docopt-0.6.1.tar.gz 21:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:05:00 INFO - Installing collected packages: blobuploader, requests, docopt 21:05:00 INFO - Running setup.py install for blobuploader 21:05:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:05:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:05:00 INFO - Running setup.py install for requests 21:05:00 INFO - Running setup.py install for docopt 21:05:00 INFO - Successfully installed blobuploader requests docopt 21:05:00 INFO - Cleaning up... 21:05:01 INFO - Return code: 0 21:05:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:01 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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:05:01 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 21:05:01 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 21:05:01 INFO - Using env: {'DISPLAY': ':0', 21:05:01 INFO - 'HOME': '/home/cltbld', 21:05:01 INFO - 'LANG': 'en_US.UTF-8', 21:05:01 INFO - 'LANGUAGE': 'en_US:en', 21:05:01 INFO - 'LOGNAME': 'cltbld', 21:05:01 INFO - 'MAIL': '/var/mail/cltbld', 21:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:01 INFO - 'MOZ_NO_REMOTE': '1', 21:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:01 INFO - 'NO_EM_RESTART': '1', 21:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:01 INFO - 'PWD': '/builds/slave/test', 21:05:01 INFO - 'SHELL': '/bin/bash', 21:05:01 INFO - 'SHLVL': '1', 21:05:01 INFO - 'TERM': 'linux', 21:05:01 INFO - 'TMOUT': '86400', 21:05:01 INFO - 'USER': 'cltbld', 21:05:01 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:01 INFO - '_': '/tools/buildbot/bin/python'} 21:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:05:01 INFO - Running setup.py (path:/tmp/pip-7d4XKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:05:01 INFO - Running setup.py (path:/tmp/pip-KwDBhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:05:01 INFO - Running setup.py (path:/tmp/pip-cBC69z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:05:01 INFO - Running setup.py (path:/tmp/pip-7p5s0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:05:01 INFO - Running setup.py (path:/tmp/pip-acLhUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:05:01 INFO - Running setup.py (path:/tmp/pip-g9xIFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:05:01 INFO - Running setup.py (path:/tmp/pip-XqG5W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:05:01 INFO - Running setup.py (path:/tmp/pip-Gbvegy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:05:01 INFO - Running setup.py (path:/tmp/pip-6C_OmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:05:01 INFO - Running setup.py (path:/tmp/pip-gC2PqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:05:02 INFO - Running setup.py (path:/tmp/pip-hY1HQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:05:02 INFO - Running setup.py (path:/tmp/pip-kvaNkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:05:02 INFO - Running setup.py (path:/tmp/pip-6VBfdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:05:02 INFO - Running setup.py (path:/tmp/pip-xgFyzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:02 INFO - Running setup.py (path:/tmp/pip-eLBtcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:05:02 INFO - Running setup.py (path:/tmp/pip-4FSulS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:05:02 INFO - Running setup.py (path:/tmp/pip-53_2VY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:05:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:05:02 INFO - Running setup.py install for manifestparser 21:05:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:05:02 INFO - Running setup.py install for mozcrash 21:05:02 INFO - Running setup.py install for mozdebug 21:05:02 INFO - Running setup.py install for mozdevice 21:05:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:05:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:05:02 INFO - Running setup.py install for mozfile 21:05:03 INFO - Running setup.py install for mozhttpd 21:05:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Running setup.py install for mozinfo 21:05:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Running setup.py install for mozInstall 21:05:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Running setup.py install for mozleak 21:05:03 INFO - Running setup.py install for mozlog 21:05:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Running setup.py install for moznetwork 21:05:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Running setup.py install for mozprocess 21:05:03 INFO - Running setup.py install for mozprofile 21:05:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:05:03 INFO - Running setup.py install for mozrunner 21:05:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:05:04 INFO - Running setup.py install for mozscreenshot 21:05:04 INFO - Running setup.py install for moztest 21:05:04 INFO - Running setup.py install for mozversion 21:05:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:05:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:05:04 INFO - Cleaning up... 21:05:04 INFO - Return code: 0 21:05:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:04 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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:05:04 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 21:05:04 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 21:05:04 INFO - Using env: {'DISPLAY': ':0', 21:05:04 INFO - 'HOME': '/home/cltbld', 21:05:04 INFO - 'LANG': 'en_US.UTF-8', 21:05:04 INFO - 'LANGUAGE': 'en_US:en', 21:05:04 INFO - 'LOGNAME': 'cltbld', 21:05:04 INFO - 'MAIL': '/var/mail/cltbld', 21:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:04 INFO - 'MOZ_NO_REMOTE': '1', 21:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:04 INFO - 'NO_EM_RESTART': '1', 21:05:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:04 INFO - 'PWD': '/builds/slave/test', 21:05:04 INFO - 'SHELL': '/bin/bash', 21:05:04 INFO - 'SHLVL': '1', 21:05:04 INFO - 'TERM': 'linux', 21:05:04 INFO - 'TMOUT': '86400', 21:05:04 INFO - 'USER': 'cltbld', 21:05:04 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:04 INFO - '_': '/tools/buildbot/bin/python'} 21:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:05:04 INFO - Running setup.py (path:/tmp/pip-V6C1LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:05:04 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)) 21:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:05:04 INFO - Running setup.py (path:/tmp/pip-mC8XlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:05:04 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)) 21:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:05:04 INFO - Running setup.py (path:/tmp/pip-tBSmQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:05:04 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)) 21:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:05:04 INFO - Running setup.py (path:/tmp/pip-atOoez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:05:04 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)) 21:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:05:04 INFO - Running setup.py (path:/tmp/pip-_bPhok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:05:04 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)) 21:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:05:04 INFO - Running setup.py (path:/tmp/pip-5IpAkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:05:04 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)) 21:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:05:04 INFO - Running setup.py (path:/tmp/pip-M4YFU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:05:05 INFO - Running setup.py (path:/tmp/pip-83vpYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:05:05 INFO - Running setup.py (path:/tmp/pip-GcmylH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:05:05 INFO - Running setup.py (path:/tmp/pip-HM0rcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:05:05 INFO - Running setup.py (path:/tmp/pip-EIXCtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:05:05 INFO - Running setup.py (path:/tmp/pip-4Y_bjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:05:05 INFO - Running setup.py (path:/tmp/pip-QK4N35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:05:05 INFO - Running setup.py (path:/tmp/pip-LDNx8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:05 INFO - Running setup.py (path:/tmp/pip-R3hy4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:05:05 INFO - Running setup.py (path:/tmp/pip-5exHPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:05:05 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)) 21:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:05:05 INFO - Running setup.py (path:/tmp/pip-f4XUBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:05:05 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)) 21:05:05 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)) 21:05:05 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)) 21:05:05 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)) 21:05:05 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)) 21:05:05 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)) 21:05:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:07 INFO - Downloading blessings-1.6.tar.gz 21:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:05:07 INFO - Installing collected packages: blessings 21:05:07 INFO - Running setup.py install for blessings 21:05:07 INFO - Successfully installed blessings 21:05:07 INFO - Cleaning up... 21:05:07 INFO - Return code: 0 21:05:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:05:08 INFO - Reading from file tmpfile_stdout 21:05:08 INFO - Current package versions: 21:05:08 INFO - argparse == 1.2.1 21:05:08 INFO - blessings == 1.6 21:05:08 INFO - blobuploader == 1.2.4 21:05:08 INFO - docopt == 0.6.1 21:05:08 INFO - manifestparser == 1.1 21:05:08 INFO - mozInstall == 1.12 21:05:08 INFO - mozcrash == 0.17 21:05:08 INFO - mozdebug == 0.1 21:05:08 INFO - mozdevice == 0.48 21:05:08 INFO - mozfile == 1.2 21:05:08 INFO - mozhttpd == 0.7 21:05:08 INFO - mozinfo == 0.9 21:05:08 INFO - mozleak == 0.1 21:05:08 INFO - mozlog == 3.2 21:05:08 INFO - moznetwork == 0.27 21:05:08 INFO - mozprocess == 0.22 21:05:08 INFO - mozprofile == 0.28 21:05:08 INFO - mozrunner == 6.11 21:05:08 INFO - mozscreenshot == 0.1 21:05:08 INFO - mozsystemmonitor == 0.3 21:05:08 INFO - moztest == 0.7 21:05:08 INFO - mozversion == 1.4 21:05:08 INFO - psutil == 3.1.1 21:05:08 INFO - requests == 1.2.3 21:05:08 INFO - wsgiref == 0.1.2 21:05:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:08 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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:05:08 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 21:05:08 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 21:05:08 INFO - Using env: {'DISPLAY': ':0', 21:05:08 INFO - 'HOME': '/home/cltbld', 21:05:08 INFO - 'LANG': 'en_US.UTF-8', 21:05:08 INFO - 'LANGUAGE': 'en_US:en', 21:05:08 INFO - 'LOGNAME': 'cltbld', 21:05:08 INFO - 'MAIL': '/var/mail/cltbld', 21:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:08 INFO - 'MOZ_NO_REMOTE': '1', 21:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:08 INFO - 'NO_EM_RESTART': '1', 21:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:08 INFO - 'PWD': '/builds/slave/test', 21:05:08 INFO - 'SHELL': '/bin/bash', 21:05:08 INFO - 'SHLVL': '1', 21:05:08 INFO - 'TERM': 'linux', 21:05:08 INFO - 'TMOUT': '86400', 21:05:08 INFO - 'USER': 'cltbld', 21:05:08 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:08 INFO - '_': '/tools/buildbot/bin/python'} 21:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 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)) 21:05:08 INFO - Cleaning up... 21:05:08 INFO - Return code: 0 21:05:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:08 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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:05:08 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 21:05:08 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 21:05:08 INFO - Using env: {'DISPLAY': ':0', 21:05:08 INFO - 'HOME': '/home/cltbld', 21:05:08 INFO - 'LANG': 'en_US.UTF-8', 21:05:08 INFO - 'LANGUAGE': 'en_US:en', 21:05:08 INFO - 'LOGNAME': 'cltbld', 21:05:08 INFO - 'MAIL': '/var/mail/cltbld', 21:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:08 INFO - 'MOZ_NO_REMOTE': '1', 21:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:08 INFO - 'NO_EM_RESTART': '1', 21:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:08 INFO - 'PWD': '/builds/slave/test', 21:05:08 INFO - 'SHELL': '/bin/bash', 21:05:08 INFO - 'SHLVL': '1', 21:05:08 INFO - 'TERM': 'linux', 21:05:08 INFO - 'TMOUT': '86400', 21:05:08 INFO - 'USER': 'cltbld', 21:05:08 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:08 INFO - '_': '/tools/buildbot/bin/python'} 21:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:08 INFO - Downloading/unpacking jsonschema 21:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:05:10 INFO - Downloading/unpacking functools32 (from jsonschema) 21:05: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 21:05: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 21:05: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 21:05: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 21:05: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 21:05: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 21:05:10 INFO - Downloading functools32-3.2.3-2.tar.gz 21:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:05:10 INFO - warning: no files found matching '*.txt' 21:05:10 INFO - no previously-included directories found matching 'build' 21:05:10 INFO - no previously-included directories found matching 'dist' 21:05:10 INFO - no previously-included directories found matching '.git*' 21:05:10 INFO - Installing collected packages: jsonschema, functools32 21:05:10 INFO - Running setup.py install for functools32 21:05:10 INFO - warning: no files found matching '*.txt' 21:05:10 INFO - no previously-included directories found matching 'build' 21:05:10 INFO - no previously-included directories found matching 'dist' 21:05:10 INFO - no previously-included directories found matching '.git*' 21:05:10 INFO - Successfully installed jsonschema functools32 21:05:10 INFO - Cleaning up... 21:05:10 INFO - Return code: 0 21:05:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:10 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 0x7fba73109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', '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 21:05:10 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 21:05:10 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 21:05:10 INFO - Using env: {'DISPLAY': ':0', 21:05:10 INFO - 'HOME': '/home/cltbld', 21:05:10 INFO - 'LANG': 'en_US.UTF-8', 21:05:10 INFO - 'LANGUAGE': 'en_US:en', 21:05:10 INFO - 'LOGNAME': 'cltbld', 21:05:10 INFO - 'MAIL': '/var/mail/cltbld', 21:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:10 INFO - 'MOZ_NO_REMOTE': '1', 21:05:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:10 INFO - 'NO_EM_RESTART': '1', 21:05:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:10 INFO - 'PWD': '/builds/slave/test', 21:05:10 INFO - 'SHELL': '/bin/bash', 21:05:10 INFO - 'SHLVL': '1', 21:05:10 INFO - 'TERM': 'linux', 21:05:10 INFO - 'TMOUT': '86400', 21:05:10 INFO - 'USER': 'cltbld', 21:05:10 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:10 INFO - '_': '/tools/buildbot/bin/python'} 21:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:11 INFO - Downloading/unpacking flake8 21:05:11 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 21:05:11 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 21:05:11 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 21:05:11 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 21:05:11 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 21:05:11 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 21:05:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:05:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:05:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:05: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 21:05: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 21:05: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 21:05: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 21:05: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 21:05: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 21:05:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:05: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 21:05: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 21:05: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 21:05: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 21:05: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 21:05: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 21:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:05:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:05:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:05:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:05:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:05:13 INFO - no previously-included directories found matching 'docs/_build' 21:05:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:05:13 INFO - Running setup.py install for pep8 21:05:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:05:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:05:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:05:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:05:13 INFO - no previously-included directories found matching 'docs/_build' 21:05:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:05:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:05:13 INFO - Cleaning up... 21:05:13 INFO - Return code: 0 21:05:13 INFO - Running post-action listener: _resource_record_post_action 21:05:13 INFO - Running post-action listener: _start_resource_monitoring 21:05:13 INFO - Starting resource monitoring. 21:05:13 INFO - ##### 21:05:13 INFO - ##### Finished create-virtualenv step (success) 21:05:13 INFO - ##### 21:05:13 INFO - ##### 21:05:13 INFO - ##### Running install step. 21:05:13 INFO - ##### 21:05:13 INFO - Running pre-action listener: _resource_record_pre_action 21:05:13 INFO - Running main action method: install 21:05:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:05:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:05:14 INFO - Reading from file tmpfile_stdout 21:05:14 INFO - Detecting whether we're running mozinstall >=1.0... 21:05:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:05:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:05:14 INFO - Reading from file tmpfile_stdout 21:05:14 INFO - Output received: 21:05:14 INFO - Usage: mozinstall [options] installer 21:05:14 INFO - Options: 21:05:14 INFO - -h, --help show this help message and exit 21:05:14 INFO - -d DEST, --destination=DEST 21:05:14 INFO - Directory to install application into. [default: 21:05:14 INFO - "/builds/slave/test"] 21:05:14 INFO - --app=APP Application being installed. [default: firefox] 21:05:14 INFO - mkdir: /builds/slave/test/build/application 21:05:14 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'] 21:05:14 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 21:05:30 INFO - Reading from file tmpfile_stdout 21:05:30 INFO - Output received: 21:05:30 INFO - /builds/slave/test/build/application/firefox/firefox 21:05:30 INFO - Running post-action listener: _resource_record_post_action 21:05:30 INFO - ##### 21:05:30 INFO - ##### Finished install step (success) 21:05:30 INFO - ##### 21:05:30 INFO - ##### 21:05:30 INFO - ##### Running run-tests step. 21:05:30 INFO - ##### 21:05:30 INFO - Running pre-action listener: _resource_record_pre_action 21:05:30 INFO - Running main action method: run_tests 21:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:05:30 INFO - Python 2.7.3 21:05:30 INFO - Return code: 0 21:05:30 INFO - grabbing minidump binary from tooltool 21:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:05:30 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 0x1b419c0>, '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 0x1b428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b42d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:05:30 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 21:05:30 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 21:05:30 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 21:05:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:05:31 INFO - Return code: 0 21:05:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:05:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:05:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:05:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:05:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:05:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:05:31 INFO - Return code: 0 21:05:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 21:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 21:05:31 INFO - Using env: {'DISPLAY': ':0', 21:05:31 INFO - 'HOME': '/home/cltbld', 21:05:31 INFO - 'LANG': 'en_US.UTF-8', 21:05:31 INFO - 'LANGUAGE': 'en_US:en', 21:05:31 INFO - 'LOGNAME': 'cltbld', 21:05:31 INFO - 'MAIL': '/var/mail/cltbld', 21:05:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:05:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:31 INFO - 'MOZ_NO_REMOTE': '1', 21:05:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:31 INFO - 'NO_EM_RESTART': '1', 21:05:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:31 INFO - 'PWD': '/builds/slave/test', 21:05:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:05:31 INFO - 'SHELL': '/bin/bash', 21:05:31 INFO - 'SHLVL': '1', 21:05:31 INFO - 'TERM': 'linux', 21:05:31 INFO - 'TMOUT': '86400', 21:05:31 INFO - 'USER': 'cltbld', 21:05:31 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754', 21:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:31 INFO - '_': '/tools/buildbot/bin/python'} 21:05:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 21:05:32 INFO - mozversion application_buildid: 20160527183007 21:05:32 INFO - mozversion application_changeset: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a 21:05:32 INFO - mozversion application_display_name: Nightly 21:05:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:05:32 INFO - mozversion application_name: Firefox 21:05:32 INFO - mozversion application_remotingname: firefox 21:05:32 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 21:05:32 INFO - mozversion application_vendor: Mozilla 21:05:32 INFO - mozversion application_version: 49.0a1 21:05:32 INFO - mozversion platform_buildid: 20160527183007 21:05:32 INFO - mozversion platform_changeset: 39dcfacc4b5b91b0d0c66ecc144aacf00fca7c2a 21:05:32 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 21:05:32 INFO - mozversion platform_version: 49.0a1 21:05:32 INFO - using testdate: 1464408332 21:05:32 INFO - actual date: 1464408332 21:05:32 INFO - starting webserver on 'localhost:58408' 21:05:32 INFO - SUITE-START | Running 2 tests 21:05:32 INFO - TEST-START | dromaeo_css 21:05:32 INFO - operating with platform_type : linux_ 21:05:32 INFO - Initialising browser for dromaeo_css test... 21:05:32 INFO - TEST-INFO | started process 27925 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt2HGTe/profile http://localhost:58408/getInfo.html) 21:05:36 INFO - PROCESS | 27925 | __metrics Screen width/height:1600/1200 21:05:36 INFO - PROCESS | 27925 | colorDepth:24 21:05:36 INFO - PROCESS | 27925 | Browser inner width/height: 1024/697 21:05:36 INFO - PROCESS | 27925 | __metrics 21:05:37 ERROR - PROCESS | 27925 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:05:38 INFO - TEST-INFO | 27925: exit 0 21:05:38 INFO - Browser initialized. 21:05:38 INFO - Running cycle 1/1 for dromaeo_css test... 21:05:38 INFO - TEST-INFO | started process 28010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt2HGTe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:05:50 INFO - PROCESS | 28010 | RSS: Main: 154910720 21:05:50 INFO - PROCESS | 28010 | 21:06:41 INFO - PROCESS | 28010 | 1464408401510 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] 21:08:49 INFO - PROCESS | 28010 | Cycle 1(1): loaded http://localhost:58408/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58408/tests/dromaeo/cssquery-ext.html) 21:08:50 INFO - PROCESS | 28010 | RSS: Main: 257679360 21:08:50 INFO - PROCESS | 28010 | 21:11:52 INFO - PROCESS | 28010 | Cycle 1(1): loaded http://localhost:58408/tests/dromaeo/cssquery-ext.html (next: http://localhost:58408/tests/dromaeo/cssquery-jquery.html) 21:11:53 INFO - PROCESS | 28010 | RSS: Main: 237281280 21:11:53 INFO - PROCESS | 28010 | 21:14:55 INFO - PROCESS | 28010 | Cycle 1(1): loaded http://localhost:58408/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58408/tests/dromaeo/cssquery-mootools.html) 21:14:56 INFO - PROCESS | 28010 | RSS: Main: 234348544 21:14:56 INFO - PROCESS | 28010 | 21:17:58 INFO - PROCESS | 28010 | Cycle 1(1): loaded http://localhost:58408/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58408/tests/dromaeo/cssquery-prototype.html) 21:17:58 INFO - PROCESS | 28010 | RSS: Main: 230686720 21:17:58 INFO - PROCESS | 28010 | 21:21:01 INFO - PROCESS | 28010 | Cycle 1(1): loaded http://localhost:58408/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58408/tests/dromaeo/cssquery-yui.html) 21:21:01 INFO - PROCESS | 28010 | RSS: Main: 226234368 21:21:01 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | Cycle 1(1): loaded http://localhost:58408/tests/dromaeo/cssquery-yui.html (next: http://localhost:58408/tests/dromaeo/cssquery-dojo.html) 21:24:04 INFO - PROCESS | 28010 | RSS: Main: 274710528 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | __start_tp_report 21:24:04 INFO - PROCESS | 28010 | _x_x_mozilla_page_load 21:24:04 INFO - PROCESS | 28010 | _x_x_mozilla_page_load_details 21:24:04 INFO - PROCESS | 28010 | |i|pagename|runs| 21:24:04 INFO - PROCESS | 28010 | |0;dojo.html;3132.3578666373396;3134.4358015557204;3156.4318422683923;3171.1754943714654;3186.665400132986;5357.651752636076;5414.377346605142;5465.988792073469;5504.779808807648;5528.530074943629;5415.810446634368;5427.83716488505;5466.180072989054;5488.862778430541;5521.2270282160425;4834.033193361333;4919.458859525467;4934.876628084291;4948.604111671076;5034.370703662043;4951.207806750921;4965.280034395028;4995.350604421438;5005.049040682259;5021.422536408305;4774.546418090275;4851.151048566487;4864.902701945941;4898.436679781835;4916.459189489154;4845.103655823648;4871.683340582851;4937.851864444073;4941.135301322254;4991.401031876198;4775.785089670973;4890.486498917598;4902.2401527762895;4917.139500587418;4953.876153096201;5345.118055520835;5396.298481197458;5415.729213539346;5479.479449452296;5480.835574932758;5866.794662186846;5898.587098903078;5915.970420147872;5919.644821310735;5923.17075609415;5637.238972738669;5791.3339965903615;5805.10020946754;5813.128030795372;5818.941810581882;5466.180072989084;5577.581681373873;5580.525655319262;5586.469285417878;5587.469190426903;6038.151355533753;6068.817935461943;6077.513798896078;6084.452399283997;6091.634501929899;5616.747246373923;5697.202391665171;5742.569307301969;5805.448482394166;5822.941770582324;5868.471837534642;5891.558133140032;5900.291964964232;5901;5910.822675319747;5801;5844.26946631671;5849.561282903799;5849.912251316234;5851.063829787214;5588.720563971785;5752.798652047159;5801.042827933344;5803.767849285981;5884.499817515559;4416.911661766747;4418.050119224382;4421.40311058003;4425.734455932655;4456.732596044248;3925.0187453136714;3941.684665226775;3949.960500394959;3969.7816620085596;3980.6019398060544;5405.270288511001;5469.480399362054;5476.931998260381;5502.202180683778;5511;5838.649681019152;5867.501262392659;5877.177195192677;5880.705964701833;5938.851528711817;5531.391546929915;5532.972335138298;5537.889242215133;5545.057340252167;5546.639468434539;4896.95103048972;4992.700437973734;4998.825041123543;5005.849824505198;5014;6022.969885150546;6032.607880487754;6100.938990610126;6103.786367477117;6105.664188469676;5590.217369568345;5905.586608937333;5911.113333000085;5917.822465326047;5919.733611987557;6021.668808215591;6059.6364218147055;6080.179175811386;6116.26604807426;6128.1726966858905;5745.91381129275;5760.884782304414;5799.101139323411;5809.477147056785;5813.3895940925595;5639.407862174413;5795.1017592273265;5893.852653683521;5902.6458412495385;5910.408959104056;5684.346300175533;5753.079507278815;5753.971230143822;5761.596688231783;5783.16144159103;5747.9137812932;5754.56840736938;5756.079027355603;5757.712114394348;5758.769649213985;5427.348718153847;5525.585581081355;5558.471945165203;5678.971605141948;5684.8294551163535;5745.454181852718;5770.682612456355;5775.104858746901;5785.768569257182;5786.276715410574;5565.582581306499;5599.300087489064;5605.831825045255;5610.270664813548;5653.265075540154 21:24:04 INFO - PROCESS | 28010 | |1;ext.html;590.9054551271797;631.5515983651607;639.6545865232774;639.6961443314425;641.080050128066;32857.17857053572;36326.45510317347;42447.151056978844;43416.348754768704;43485.13029739411;44722.77638611811;45259;45300.320495192514;45310;45338.54661453384;8731.126887311282;8923.063078376774;8926.86609700855;8928.687495937644;8929.107089291085;8774.517401542913;8923.598438070303;8939.865902011476;8939.92720873496;8950.328725345593;8914.197722205;8934.10658934108;8981.281497480186;8985.550722463851;8986.955065224665;8859.80392646994;8969.820603587892;8976.012638609747;8976.192142707156;8977.326700497424;8753.956230218808;8855.380123391365;8855.822883542292;8857.468551886908;8866.86699699505;41980.79009604932;42350.57649423497;42417.151656966686;42479.787601062104;42594.78702606498;3759.3797023491093;3881.611838816124;4228.830846766125;4259.339802330613;4266.466691663542;32035.51946720801;32712.67287327144;32924.34151316984;33212.169695757555;33272.6672733272;4420.226460369455;4460.77696115193;4517.164324599959;4667.15991121598;4668.253079507296;354713;355734;357449;357476;358328.9999999974;61728.38271617316;62908.68545657242;63220.994005569395;63236.68381658154;63335.683321584016;63043.36956630467;63108;63445;63452;63555.68222158952;17056.658866822843;17243.913780431016;17253;17309.480715578553;17329.960202387898;12724.618261452171;12756.61730148097;12764.425600847984;12765.234085954873;12773;16442.66602856394;18707;18809.02505236958;18944;19197.424077277705;5593.440655934455;6471.417572418411;6553.180852393451;6715.529912906148;6730.865382692319;1001.6344034427382;1021.7139201023725;1036.0279975412889;1050.1965996013057;1093.2347356850237;31792.682073179436;34119.31761364759;34218.31563368718;34218.657813421545;34320.82839585786;1092.7978324010085;1115.8305363313293;1123.9831402528969;1140;1180.144395313391;17425.39011134604;17708.645827083386;17853.821461785476;17912.55218619519;17941.7308740369;549535;552924;554246;556209;557144;62175.689121555006;62811.371886280554;62818.68590657017;62922.37077629257;62999.37000629935;1231.987680123205;1260.1179174577833;1353.2759973414372;1381.405995421978;1417.8865690744715;30940.381192376477;35119;35146.82426587851;35266.29467410689;35346.40289198802;52220;52718.73640631926;52812.47187528075;52888.735556321975;52919;449400;452419;452456;454024;454548;45065;45713.54286457226;45849.08301834011;46362.53637463717;48578.27132592943;50312.24531631955;50476.49523504865;50836.49163508318;51338.48661513486;51847;41511;42108.789456052524;42653.786731066146;43367.13265734605;44253.55746442494;42554.5744542563;42737.58099351982;42797.786011069744;42860.35709464423;43530.782346089334;40647.59352406557;42002;42752.14495710131;43051.78474107735;43096;36376.81811590925;37186.62813371832;37293.81353093217;37727.62272377351;38248.426273605364;28249.71750282553;28431.43137137287;28602.284942876595;28684.85657571699;28693.856530718047 21:24:04 INFO - PROCESS | 28010 | |2;jquery.html;384.46367317591967;386.98896201937885;389.929812633726;401.83725591135584;406.40258819535273;18063.36778212763;18523.2590696372;19569.217231310748;19593.70609440858;19809.60380792383;18294.634107317845;18779.812201877976;19113.426597202073;19631.60736785267;19634.509137271576;5434.864128396792;5545.973994810973;5609.1305847593485;5657.915131273035;5659.717014149276;5482;5540.9168862467095;5541.168824676311;5595.664260144384;5601.915971260434;5463.808766693167;5476.123820188771;5557.194206840006;5577.274954255961;5591.049521581321;5529.529989950859;5562.360328562227;5572.192032155335;5586.86032849178;5587.860303492445;4761.523847615211;4915;4921.163402221631;4932.235503496964;4940.25896115582;23001.19495817655;23311.067557297687;23329.53340933189;23452.296431107094;23720.64419033716;2356.9528609427716;2387.8806059697117;2532.4428625702317;2579.1746641074865;2871.7559007484388;14413;14450.855491445056;14559.272036398137;14613.926930365387;14954.551363459113;1844.9446516604523;1963.234338607944;1986.7814540400545;2027.3208475160839;2063.8245749111343;60614.69692651553;60854;85507.57246213792;88775;88965.55517222437;9421.576029078708;9582.616695332108;9704.320697551104;10278.434686092187;10755.354678719303;13591.456341746418;14420.351084201237;14421.206833624252;14451.49419770303;14925.328360223815;8193.79515512105;8220.547869867096;8226.629801658939;8270.8345833083;8569.35729820266;8127.146649601825;8190.303824175012;8196.139405362352;8277.31659411556;8305.626246818907;4524.715018915426;4529.728216306966;4562.338460923214;4569.314602809605;4651.37206477128;1883.482042438313;1885.9811401885806;1902.7050807124917;1912.4167129025502;1915.5402703351374;856.905740368559;979.7109852593502;989.5942663508071;1028.3521381529588;1087.8531398261134;19081.141348639343;19368.322108726126;19543.120559574854;19616.117274722386;19981.300654477312;895.6104094718818;941.5762906692016;963.3834346018554;1000.8798944126752;1039.3088596083671;10935.398553079656;11020.834687479693;11063.336199828036;11081.445927703582;11122.721931951604;498240;504353;556053;559074;561756;95459.52270238605;99828;102142.4892875531;103062;103612;1034.6740776655329;1047.601911273711;1074.7313171707074;1083.3987137138963;1086.6142519405498;17606.20772065286;17714.822851771834;18098.81901180971;18130.909345453714;18426.262949482407;40191;40313.96075490564;41456.58543414527;41602.791986039876;41720.582794172886;52716.20925686041;53676;55828.44171558395;58854;61188.08217876646;8447.662093516436;8499.447535910145;8706.390552661253;8722.825543489222;9007.954960225157;9666.178374837937;13661.79507307411;13662.658433539242;13938.790918136034;14036.929815351266;12139.817902731642;12301.569445069379;12381.62855114348;12385.256884586954;12557.811632825333;6480.513961453004;8825;11903.404829758305;11968;12472.189307694971;10947.781044378908;12261.754764904474;12552.121351505308;12571.497140114294;12713.55502557406;7427.5914824683;7673.731419400294;7791.142974272712;8683.2185103341;8908.420952638058;9076.137766912132;11230.719232019264;12003.939980300393;12082.214656047328;12255.509779608716 21:24:04 INFO - PROCESS | 28010 | |3;mootools.html;1338.0633556510445;1498.7452133137365;1513.5005448202094;1686.6542358816444;1810.0768608009919;8302.294304984076;8590.44162129462;8611.224989750914;8876.600552975115;8896.288296936245;8638.136186381358;8802.07578204289;8804.515751633673;8811.427257228275;8812.735617931472;2051.569170474202;2115.629764791163;2117.216629846951;2384.141708984764;2415.10641062807;2371.7865392114704;2441.8046556275453;2443.1204766284213;2453.877306134695;2459.6433517140003;2370.6799582056374;2400.603900356439;2402.423418379585;2413.5776239158163;2422.0311875249863;2077.8753274803407;2142.4965133193687;2291.301153148289;2319.512902290521;2321.8374713770045;2094.832413406924;2291.301153148289;2348.3659411958674;2349.97650023501;2357.3281614739717;8596.484210947301;8631;8657.740267791976;8660.610272537751;8665.263452606536;5187.221916712523;5314.627976041678;5316.680999140025;5318.627696061278;5323.121684921984;3438.381091403546;3468.236987862666;3469.202083520779;3479.791212527231;3484.5818501779695;1103.382106020631;1124.6457365929707;1157.6584907452323;1167.5388221652486;1180.1797750563362;11735.295882247092;11752.23610465317;11759.882401175964;11806.350650714206;11832;4514.232580461329;4600.88497787558;4694.976525117352;4699.95300047002;4702.388689470416;10223.028812262823;10248.180145588334;10254.230932680079;10255.692329230134;10256.28206025586;5216.295800067019;5217.452167522431;5222.060029194781;5224.8171314004585;5228.581713462914;4387.232234359039;4610.193216187154;4742.786574604151;4832.734199619054;4902.068606964665;3913.706472014553;3970.285348637215;4019.21625283067;4019.397090436458;4183.477065366829;1316.440512782083;1333.4932725564224;1337.7926421404698;1339.1362571141754;1343.6170691352918;895.2122132523468;1021.22897212604;1030.9278350515392;1033.9402112834405;1034.5189486888632;5809.302883653989;5969.970150149308;6007.15899774032;6175.722092505758;6182.258129024456;472.5912086045615;472.8983268597268;483.84844071111235;486.8052778888473;487.3104557051803;4223.387608796707;4225.746455212697;4226.598473145046;4234.364845273172;4245.915081698348;10536.578536858593;10872.72818179536;10987.945060274647;10993.450327483593;10997.010269075821;10835.458227088615;10848.837267440995;10852.294600850919;10878.238523303451;10883.292585981886;7793.298603125746;8047.637856296481;8096.190380961857;8227.71203007892;8346.540940248224;6097.390260974044;6210.689465526615;6250.749970001149;6371.6814159290925;6407.359264073555;9798.461084640414;9972.700818975442;10138.543765530421;10145.543450544596;10152.340097893613;10169.237307201842;10268.794624107326;10333.586656533655;10351.275410721177;10420.68737937863;7093.787186384417;7637.579933103732;7800.219978002155;7839.490433121829;7962.800929976797;8699.608517616596;8701.782455438664;8772.824543509221;8782.209601135928;8787.25308348772;7460.440466964891;7795.766127016199;7841.764747057369;7928.246816552649;8050.959745201237;7439.776806695808;7509.136449308399;7521.586312752632;7561.848763024819;7739.264769846855;7231.05996220509;7268.745593904188;7292.96353518229;7302.342789149002;7309.853802923805;5216.582673386044;5257.658252213594;5303.946960530346;5324.1747529133045;5373.05971454986;5327.733613319396;5456.399796022355;5479.561635069105;5509.779608815603;5597.748101335368 21:24:04 INFO - PROCESS | 28010 | |4;prototype.html;316.0518444666002;319.5590085681759;321.96941290577394;323.1887961217345;324.70289684938285;5404.621676482643;5420.473904289917;5436.565074794017;5449.536789372908;5486.231927530149;5287.762050707718;5290.68255904646;5305.806193606437;5318.1225097858905;5358.65168764031;4032.6773858091356;4046;4200.516940551845;4218.746875187483;4223.831046758126;4124.443200167969;4177.853775117872;4182.728122672017;4196.58034196581;4199.517055538607;3959.9604003960026;4146.502419709624;4153.1070819773895;4160.750354978696;4179.623833854953;4014.257362387953;4016.979915100406;4068.2270368629866;4118.567550407224;4124.463819703419;3627.546556680415;3640.2173532690335;3641.3445579795625;3655.670989610921;3661.4690869823726;5096.566791822699;5141.30592370029;5143.125668636303;5148.330717006766;5188.11801993662;4552.590266875964;4678.812847486097;4707.599854012413;4731.2430011198385;4749.240121580565;5452.509274165344;5474.315710536183;5499.972500137514;5514.91727624086;5523.260173045518;4012.8394864205397;4022.3564229723397;4024.3561030235023;4035.1122752994293;4038.192361527706;5708.514776244025;5735.483806457439;5739.253896993364;5739.540836733093;5748.19525266463;4056.411820286042;4074.575627501632;4091.754494730326;4098.446709694213;4113.526944401372;4166.937430955087;4176.248275310473;4179.393987871742;4186.392973018896;4221.176870510282;4561.521040290788;4717.646176536773;4732.13080833069;4733.36099626546;4744.979829336709;5522.751476183581;5559.471850174227;5571.4428557143965;5583.804566840141;5590.692511911884;1447.7683570628649;1452.869241768246;1454.549089782171;1459.9854001459848;1465.4065103633045;929.4423345992351;946.8106378724283;949.5442187749919;969.7042402067297;973.3235401395962;5356.705381203993;5370.409254981949;5390.407055223922;5395.379531353945;5413.377461591967;5733.455321744427;5738.454846789548;5749.68376739275;5754.108113242454;5767;5219.869503262449;5380.78476860929;5384.461553844662;5394.406615272317;5400.21696853954;2990.1926479850317;3022.6070610820893;3025.667176610571;3037.16477968556;3053.3740583180393;5640;5854.912176317359;5896.260603122089;5921.526277897844;5924.407559244041;5684.37471878093;5716.57125715573;5778.682172480554;5787.189793428924;5809.157672137517;5786.71066446676;5821.767129314865;5866.7066646668345;5879.823605291848;5882.735276912549;5622.85942851432;5657.971710141423;5665.49010589049;5709.743061562157;5777.884442311046;5570.498655121059;5591.804286849941;5601.887962240814;5642.407547207484;5643.830685079454;5596.468335508121;5891.764329426775;5899.882002359843;5905.52755779528;5923.259592550931;4033.213523362916;4041.474608300902;4044.5934668871973;4045;4050.736702114353;5488.204210389551;5637.774489020558;5643.802466913638;5651.660900345828;5686.43135686428;5576.553875690016;5589.888202236014;5607.663540187602;5608.551315894801;5625.240592519957;5550.417206193291;5580.135079062752;5592.133219351007;5593.776248949996;5594.272744543184;5292;5504.91742623869;5516.889662206813;5565.5825813063375;5591.916121258266;5636.210930469655;5679.148127780701;5686.445971267957;5714.771409143587;5717.942820571741;5472.91790623149;5597.356304024926;5604.131359639263;5624.409437009055;5650 21:24:04 INFO - PROCESS | 28010 | |5;yui.html;801.5591424716408;807.2774866494487;809.7894547417669;814.1614137438437;857.982840343193;13912.791308130374;14698.191599462025;14736.852631473696;14825.258737063145;14858.108513489198;14085.788713169311;14134;14555.272236388164;14596.27018649066;14617.488387906425;684.284922256241;824.1717074340295;826.4297634632114;828.9419740618159;830.7839961609994;794.9165337639552;819.1439945257221;819.6967122164807;825.2778818533783;825.8348330333957;856.1310270075878;856.755824589992;858.9312854971587;859.879616853641;859.9011113721939;706.7738323736406;824.2746383182821;855.6791203298762;858.1847245117133;858.549261637639;615.7075389190144;617.660286842235;617.8208319587314;618.6473709985311;618.8576627375684;15473.458428955046;15494.61263468411;15500.224988750517;15522.5343239703;15527.611809704735;14323.85676143246;14441.711165776625;14510.274486275644;14512.56462306122;14685;13140.277284749334;13143.671408214776;13168;13359.799603005962;13978.231197284145;4526.298423744325;4545.931811022837;4562.726236425825;4576.405067341258;4739.976300118477;65654;66251.33748662547;66810.66594667044;66826.66586667036;67372;11604.419779011016;11675.532978680758;11816.88183118175;11845.348505832262;11877.821832672516;21380.358589242347;21484.140634374762;21503.2473863414;21518;21555.353339399844;8502.489850608983;8589.527575983257;8676.522791246416;8679.26226270774;8728.869066964;4415.492218394861;4702.95297047032;4833.202521583969;4843.4430040545085;4907.509249075135;3555.822208889545;3649.489071529988;3657.3233951719017;3658.140337020798;3668.706503479715;662.5262937000068;669.7722774256777;674.436845234235;685.3694600967123;699.9300069993062;666.6466772610427;737.3253473072186;741.4550305525435;755.7883792538098;765.7090305683804;9515.619375224913;9854.260930430102;10045.397276163454;10140.898591014144;10262.743431414274;628.7987843889912;666.8657310640011;708.7950484176816;768.7078910014159;770.6416516319938;4846;4963.329950456714;5029.748512574357;5030.8239211627415;5061.898762024813;65846;68732.65633671799;69898;70945;79698.6015069921;58641;58692.70653646704;59088.40911590915;59909;60845.39154608397;53968.46031539634;54355.45644543583;54687.45312546824;55276.447235527936;55830.72084639632;8930.298407760878;9694.709158725249;9856.42064708045;10083.344582602107;10118.49407529606;37797;37847.40449657278;37877.431838521894;38181.23637527179;38372.42441363438;50845.237321440945;51099.74450127726;51321.743391282806;51728.224076639635;52199.47800522098;12956;12978.351082446028;12992.610221693365;13084.869151308745;13545.32273386307;14219.360128794437;14732.55802325932;15003.549893503212;15513.76729349038;15518.844811552191;13117.278549679859;13379.197248165141;13543.932280338535;13882;14151.929240353733;12932.517778109528;13083.803742943674;13620.31898405056;13981;14218.573442796733;12525.311107889154;12560.811587826007;12568.87431125677;12742.554010609585;13152.408141633841;10905.405270463314;11190.667979354916;11453.484593193492;11538.192326537062;11710.297382157098;11122.721931951442;11223;11338.773224535627;11370.147238956946;11457.484413201259 21:24:04 INFO - PROCESS | 28010 | __end_tp_report 21:24:04 INFO - PROCESS | 28010 | __start_cc_report 21:24:04 INFO - PROCESS | 28010 | _x_x_mozilla_cycle_collect,869 21:24:04 INFO - PROCESS | 28010 | __end_cc_report 21:24:04 INFO - PROCESS | 28010 | __startTimestamp1464409444976__endTimestamp 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | ------- Summary: start ------- 21:24:04 INFO - PROCESS | 28010 | Number of tests: 6 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | [#0] dojo.html Cycles:165 Average:5423.09 Median:5605.83 stddev:634.97 (11.3%) stddev-sans-first:610.96 21:24:04 INFO - PROCESS | 28010 | Values: 3132.4 3134.4 3156.4 3171.2 3186.7 5357.7 5414.4 5466.0 5504.8 5528.5 5415.8 5427.8 5466.2 5488.9 5521.2 4834.0 4919.5 4934.9 4948.6 5034.4 4951.2 4965.3 4995.4 5005.0 5021.4 4774.5 4851.2 4864.9 4898.4 4916.5 4845.1 4871.7 4937.9 4941.1 4991.4 4775.8 4890.5 4902.2 4917.1 4953.9 5345.1 5396.3 5415.7 5479.5 5480.8 5866.8 5898.6 5916.0 5919.6 5923.2 5637.2 5791.3 5805.1 5813.1 5818.9 5466.2 5577.6 5580.5 5586.5 5587.5 6038.2 6068.8 6077.5 6084.5 6091.6 5616.7 5697.2 5742.6 5805.4 5822.9 5868.5 5891.6 5900.3 5901.0 5910.8 5801.0 5844.3 5849.6 5849.9 5851.1 5588.7 5752.8 5801.0 5803.8 5884.5 4416.9 4418.1 4421.4 4425.7 4456.7 3925.0 3941.7 3950.0 3969.8 3980.6 5405.3 5469.5 5476.9 5502.2 5511.0 5838.6 5867.5 5877.2 5880.7 5938.9 5531.4 5533.0 5537.9 5545.1 5546.6 4897.0 4992.7 4998.8 5005.8 5014.0 6023.0 6032.6 6100.9 6103.8 6105.7 5590.2 5905.6 5911.1 5917.8 5919.7 6021.7 6059.6 6080.2 6116.3 6128.2 5745.9 5760.9 5799.1 5809.5 5813.4 5639.4 5795.1 5893.9 5902.6 5910.4 5684.3 5753.1 5754.0 5761.6 5783.2 5747.9 5754.6 5756.1 5757.7 5758.8 5427.3 5525.6 5558.5 5679.0 5684.8 5745.5 5770.7 5775.1 5785.8 5786.3 5565.6 5599.3 5605.8 5610.3 5653.3 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | [#1] ext.html Cycles:180 Average:62617.40 Median:43391.74 stddev:122291.41 (281.8%) stddev-sans-first:122545.79 21:24:04 INFO - PROCESS | 28010 | Values: 590.9 631.6 639.7 639.7 641.1 32857.2 36326.5 42447.2 43416.3 43485.1 44722.8 45259.0 45300.3 45310.0 45338.5 8731.1 8923.1 8926.9 8928.7 8929.1 8774.5 8923.6 8939.9 8939.9 8950.3 8914.2 8934.1 8981.3 8985.6 8987.0 8859.8 8969.8 8976.0 8976.2 8977.3 8754.0 8855.4 8855.8 8857.5 8866.9 41980.8 42350.6 42417.2 42479.8 42594.8 3759.4 3881.6 4228.8 4259.3 4266.5 32035.5 32712.7 32924.3 33212.2 33272.7 4420.2 4460.8 4517.2 4667.2 4668.3 354713.0 355734.0 357449.0 357476.0 358329.0 61728.4 62908.7 63221.0 63236.7 63335.7 63043.4 63108.0 63445.0 63452.0 63555.7 17056.7 17243.9 17253.0 17309.5 17330.0 12724.6 12756.6 12764.4 12765.2 12773.0 16442.7 18707.0 18809.0 18944.0 19197.4 5593.4 6471.4 6553.2 6715.5 6730.9 1001.6 1021.7 1036.0 1050.2 1093.2 31792.7 34119.3 34218.3 34218.7 34320.8 1092.8 1115.8 1124.0 1140.0 1180.1 17425.4 17708.6 17853.8 17912.6 17941.7 549535.0 552924.0 554246.0 556209.0 557144.0 62175.7 62811.4 62818.7 62922.4 62999.4 1232.0 1260.1 1353.3 1381.4 1417.9 30940.4 35119.0 35146.8 35266.3 35346.4 52220.0 52718.7 52812.5 52888.7 52919.0 449400.0 452419.0 452456.0 454024.0 454548.0 45065.0 45713.5 45849.1 46362.5 48578.3 50312.2 50476.5 50836.5 51338.5 51847.0 41511.0 42108.8 42653.8 43367.1 44253.6 42554.6 42737.6 42797.8 42860.4 43530.8 40647.6 42002.0 42752.1 43051.8 43096.0 36376.8 37186.6 37293.8 37727.6 38248.4 28249.7 28431.4 28602.3 28684.9 28693.9 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | [#2] jquery.html Cycles:180 Average:30395.58 Median:385.73 stddev:88387.68 (22914.6%) stddev-sans-first:88606.91 21:24:04 INFO - PROCESS | 28010 | Values: 384.5 387.0 389.9 401.8 406.4 18063.4 18523.3 19569.2 19593.7 19809.6 18294.6 18779.8 19113.4 19631.6 19634.5 5434.9 5546.0 5609.1 5657.9 5659.7 5482.0 5540.9 5541.2 5595.7 5601.9 5463.8 5476.1 5557.2 5577.3 5591.0 5529.5 5562.4 5572.2 5586.9 5587.9 4761.5 4915.0 4921.2 4932.2 4940.3 23001.2 23311.1 23329.5 23452.3 23720.6 2357.0 2387.9 2532.4 2579.2 2871.8 14413.0 14450.9 14559.3 14613.9 14954.6 1844.9 1963.2 1986.8 2027.3 2063.8 60614.7 60854.0 85507.6 88775.0 88965.6 9421.6 9582.6 9704.3 10278.4 10755.4 13591.5 14420.4 14421.2 14451.5 14925.3 8193.8 8220.5 8226.6 8270.8 8569.4 8127.1 8190.3 8196.1 8277.3 8305.6 4524.7 4529.7 4562.3 4569.3 4651.4 1883.5 1886.0 1902.7 1912.4 1915.5 856.9 979.7 989.6 1028.4 1087.9 19081.1 19368.3 19543.1 19616.1 19981.3 895.6 941.6 963.4 1000.9 1039.3 10935.4 11020.8 11063.3 11081.4 11122.7 498240.0 504353.0 556053.0 559074.0 561756.0 95459.5 99828.0 102142.5 103062.0 103612.0 1034.7 1047.6 1074.7 1083.4 1086.6 17606.2 17714.8 18098.8 18130.9 18426.3 40191.0 40314.0 41456.6 41602.8 41720.6 52716.2 53676.0 55828.4 58854.0 61188.1 8447.7 8499.4 8706.4 8722.8 9008.0 9666.2 13661.8 13662.7 13938.8 14036.9 12139.8 12301.6 12381.6 12385.3 12557.8 6480.5 8825.0 11903.4 11968.0 12472.2 10947.8 12261.8 12552.1 12571.5 12713.6 7427.6 7673.7 7791.1 8683.2 8908.4 9076.1 11230.7 12003.9 12082.2 12255.5 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | [#3] mootools.html Cycles:180 Average:5808.20 Median:4652.58 stddev:3275.05 (70.4%) stddev-sans-first:3267.01 21:24:04 INFO - PROCESS | 28010 | Values: 1338.1 1498.7 1513.5 1686.7 1810.1 8302.3 8590.4 8611.2 8876.6 8896.3 8638.1 8802.1 8804.5 8811.4 8812.7 2051.6 2115.6 2117.2 2384.1 2415.1 2371.8 2441.8 2443.1 2453.9 2459.6 2370.7 2400.6 2402.4 2413.6 2422.0 2077.9 2142.5 2291.3 2319.5 2321.8 2094.8 2291.3 2348.4 2350.0 2357.3 8596.5 8631.0 8657.7 8660.6 8665.3 5187.2 5314.6 5316.7 5318.6 5323.1 3438.4 3468.2 3469.2 3479.8 3484.6 1103.4 1124.6 1157.7 1167.5 1180.2 11735.3 11752.2 11759.9 11806.4 11832.0 4514.2 4600.9 4695.0 4700.0 4702.4 10223.0 10248.2 10254.2 10255.7 10256.3 5216.3 5217.5 5222.1 5224.8 5228.6 4387.2 4610.2 4742.8 4832.7 4902.1 3913.7 3970.3 4019.2 4019.4 4183.5 1316.4 1333.5 1337.8 1339.1 1343.6 895.2 1021.2 1030.9 1033.9 1034.5 5809.3 5970.0 6007.2 6175.7 6182.3 472.6 472.9 483.8 486.8 487.3 4223.4 4225.7 4226.6 4234.4 4245.9 10536.6 10872.7 10987.9 10993.5 10997.0 10835.5 10848.8 10852.3 10878.2 10883.3 7793.3 8047.6 8096.2 8227.7 8346.5 6097.4 6210.7 6250.7 6371.7 6407.4 9798.5 9972.7 10138.5 10145.5 10152.3 10169.2 10268.8 10333.6 10351.3 10420.7 7093.8 7637.6 7800.2 7839.5 7962.8 8699.6 8701.8 8772.8 8782.2 8787.3 7460.4 7795.8 7841.8 7928.2 8051.0 7439.8 7509.1 7521.6 7561.8 7739.3 7231.1 7268.7 7293.0 7302.3 7309.9 5216.6 5257.7 5303.9 5324.2 5373.1 5327.7 5456.4 5479.6 5509.8 5597.7 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | [#4] prototype.html Cycles:180 Average:4693.89 Median:5409.00 stddev:1376.58 (25.4%) stddev-sans-first:1340.66 21:24:04 INFO - PROCESS | 28010 | Values: 316.1 319.6 322.0 323.2 324.7 5404.6 5420.5 5436.6 5449.5 5486.2 5287.8 5290.7 5305.8 5318.1 5358.7 4032.7 4046.0 4200.5 4218.7 4223.8 4124.4 4177.9 4182.7 4196.6 4199.5 3960.0 4146.5 4153.1 4160.8 4179.6 4014.3 4017.0 4068.2 4118.6 4124.5 3627.5 3640.2 3641.3 3655.7 3661.5 5096.6 5141.3 5143.1 5148.3 5188.1 4552.6 4678.8 4707.6 4731.2 4749.2 5452.5 5474.3 5500.0 5514.9 5523.3 4012.8 4022.4 4024.4 4035.1 4038.2 5708.5 5735.5 5739.3 5739.5 5748.2 4056.4 4074.6 4091.8 4098.4 4113.5 4166.9 4176.2 4179.4 4186.4 4221.2 4561.5 4717.6 4732.1 4733.4 4745.0 5522.8 5559.5 5571.4 5583.8 5590.7 1447.8 1452.9 1454.5 1460.0 1465.4 929.4 946.8 949.5 969.7 973.3 5356.7 5370.4 5390.4 5395.4 5413.4 5733.5 5738.5 5749.7 5754.1 5767.0 5219.9 5380.8 5384.5 5394.4 5400.2 2990.2 3022.6 3025.7 3037.2 3053.4 5640.0 5854.9 5896.3 5921.5 5924.4 5684.4 5716.6 5778.7 5787.2 5809.2 5786.7 5821.8 5866.7 5879.8 5882.7 5622.9 5658.0 5665.5 5709.7 5777.9 5570.5 5591.8 5601.9 5642.4 5643.8 5596.5 5891.8 5899.9 5905.5 5923.3 4033.2 4041.5 4044.6 4045.0 4050.7 5488.2 5637.8 5643.8 5651.7 5686.4 5576.6 5589.9 5607.7 5608.6 5625.2 5550.4 5580.1 5592.1 5593.8 5594.3 5292.0 5504.9 5516.9 5565.6 5591.9 5636.2 5679.1 5686.4 5714.8 5717.9 5472.9 5597.4 5604.1 5624.4 5650.0 21:24:04 INFO - PROCESS | 28010 | 21:24:04 INFO - PROCESS | 28010 | [#5] yui.html Cycles:180 Average:16409.39 Median:4721.46 stddev:19510.86 (413.2%) stddev-sans-first:19530.38 21:24:04 INFO - PROCESS | 28010 | Values: 801.6 807.3 809.8 814.2 858.0 13912.8 14698.2 14736.9 14825.3 14858.1 14085.8 14134.0 14555.3 14596.3 14617.5 684.3 824.2 826.4 828.9 830.8 794.9 819.1 819.7 825.3 825.8 856.1 856.8 858.9 859.9 859.9 706.8 824.3 855.7 858.2 858.5 615.7 617.7 617.8 618.6 618.9 15473.5 15494.6 15500.2 15522.5 15527.6 14323.9 14441.7 14510.3 14512.6 14685.0 13140.3 13143.7 13168.0 13359.8 13978.2 4526.3 4545.9 4562.7 4576.4 4740.0 65654.0 66251.3 66810.7 66826.7 67372.0 11604.4 11675.5 11816.9 11845.3 11877.8 21380.4 21484.1 21503.2 21518.0 21555.4 8502.5 8589.5 8676.5 8679.3 8728.9 4415.5 4703.0 4833.2 4843.4 4907.5 3555.8 3649.5 3657.3 3658.1 3668.7 662.5 669.8 674.4 685.4 699.9 666.6 737.3 741.5 755.8 765.7 9515.6 9854.3 10045.4 10140.9 10262.7 628.8 666.9 708.8 768.7 770.6 4846.0 4963.3 5029.7 5030.8 5061.9 65846.0 68732.7 69898.0 70945.0 79698.6 58641.0 58692.7 59088.4 59909.0 60845.4 53968.5 54355.5 54687.5 55276.4 55830.7 8930.3 9694.7 9856.4 10083.3 10118.5 37797.0 37847.4 37877.4 38181.2 38372.4 50845.2 51099.7 51321.7 51728.2 52199.5 12956.0 12978.4 12992.6 13084.9 13545.3 14219.4 14732.6 15003.5 15513.8 15518.8 13117.3 13379.2 13543.9 13882.0 14151.9 12932.5 13083.8 13620.3 13981.0 14218.6 12525.3 12560.8 12568.9 12742.6 13152.4 10905.4 11190.7 11453.5 11538.2 11710.3 11122.7 11223.0 11338.8 11370.1 11457.5 21:24:04 INFO - PROCESS | 28010 | -------- Summary: end -------- 21:24:04 INFO - PROCESS | 28010 | 21:24:05 INFO - TEST-INFO | 28010: exit 0 21:24:05 INFO - TEST-OK | dromaeo_css | took 1113565ms 21:24:05 INFO - TEST-START | kraken 21:24:05 INFO - operating with platform_type : linux_ 21:24:05 INFO - Initialising browser for kraken test... 21:24:05 INFO - TEST-INFO | started process 28193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqSzLjl/profile http://localhost:58408/getInfo.html) 21:24:10 INFO - PROCESS | 28193 | __metrics Screen width/height:1600/1200 21:24:10 INFO - PROCESS | 28193 | colorDepth:24 21:24:10 INFO - PROCESS | 28193 | Browser inner width/height: 1024/697 21:24:10 INFO - PROCESS | 28193 | __metrics 21:24:11 ERROR - PROCESS | 28193 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:24:11 INFO - TEST-INFO | 28193: exit 0 21:24:11 INFO - Browser initialized. 21:24:11 INFO - Running cycle 1/1 for kraken test... 21:24:11 INFO - TEST-INFO | started process 28276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqSzLjl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:24:23 INFO - PROCESS | 28276 | RSS: Main: 155545600 21:24:23 INFO - PROCESS | 28276 | 21:24:26 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58408/tests/kraken/driver.html?testName=audio-beat-detection) 21:24:27 INFO - PROCESS | 28276 | RSS: Main: 211496960 21:24:27 INFO - PROCESS | 28276 | 21:24:29 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58408/tests/kraken/driver.html?testName=audio-dft) 21:24:30 INFO - PROCESS | 28276 | RSS: Main: 198758400 21:24:30 INFO - PROCESS | 28276 | 21:24:33 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58408/tests/kraken/driver.html?testName=audio-fft) 21:24:34 INFO - PROCESS | 28276 | RSS: Main: 268263424 21:24:34 INFO - PROCESS | 28276 | 21:24:36 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58408/tests/kraken/driver.html?testName=audio-oscillator) 21:24:36 INFO - PROCESS | 28276 | RSS: Main: 176607232 21:24:36 INFO - PROCESS | 28276 | 21:24:39 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58408/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:24:39 INFO - PROCESS | 28276 | RSS: Main: 181661696 21:24:39 INFO - PROCESS | 28276 | 21:24:42 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58408/tests/kraken/driver.html?testName=imaging-darkroom) 21:24:43 INFO - PROCESS | 28276 | RSS: Main: 217145344 21:24:43 INFO - PROCESS | 28276 | 21:24:46 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58408/tests/kraken/driver.html?testName=imaging-desaturate) 21:24:47 INFO - PROCESS | 28276 | RSS: Main: 217817088 21:24:47 INFO - PROCESS | 28276 | 21:24:50 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58408/tests/kraken/driver.html?testName=json-parse-financial) 21:24:50 INFO - PROCESS | 28276 | RSS: Main: 218009600 21:24:50 INFO - PROCESS | 28276 | 21:24:52 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58408/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:24:52 INFO - PROCESS | 28276 | RSS: Main: 206077952 21:24:52 INFO - PROCESS | 28276 | 21:24:54 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:24:55 INFO - PROCESS | 28276 | RSS: Main: 208556032 21:24:55 INFO - PROCESS | 28276 | 21:24:57 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:24:57 INFO - PROCESS | 28276 | RSS: Main: 218816512 21:24:57 INFO - PROCESS | 28276 | 21:25:00 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:25:01 INFO - PROCESS | 28276 | RSS: Main: 226607104 21:25:01 INFO - PROCESS | 28276 | 21:25:03 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:25:04 INFO - PROCESS | 28276 | RSS: Main: 223469568 21:25:04 INFO - PROCESS | 28276 | 21:25:05 INFO - PROCESS | 28276 | Cycle 1(1): loaded http://localhost:58408/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58408/tests/kraken/driver.html?testName=ai-astar) 21:25:06 INFO - PROCESS | 28276 | RSS: Main: 269258752 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | __start_tp_report 21:25:06 INFO - PROCESS | 28276 | _x_x_mozilla_page_load 21:25:06 INFO - PROCESS | 28276 | _x_x_mozilla_page_load_details 21:25:06 INFO - PROCESS | 28276 | |i|pagename|runs| 21:25:06 INFO - PROCESS | 28276 | |0;ai-astar;111;105;110;109;112;112;110;109;110;111 21:25:06 INFO - PROCESS | 28276 | |1;audio-beat-detection;165;124;126;181;127;125;157;124;199;124 21:25:06 INFO - PROCESS | 28276 | |2;audio-dft;161;161;160;161;160;159;161;161;161;160 21:25:06 INFO - PROCESS | 28276 | |3;audio-fft;74;75;76;112;76;76;74;75;97;74 21:25:06 INFO - PROCESS | 28276 | |4;audio-oscillator;83;82;124;82;83;83;118;84;84;124 21:25:06 INFO - PROCESS | 28276 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;117;117 21:25:06 INFO - PROCESS | 28276 | |6;imaging-darkroom;145;147;146;146;145;145;146;145;145;147 21:25:06 INFO - PROCESS | 28276 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;96;96 21:25:06 INFO - PROCESS | 28276 | |8;json-parse-financial;54;53;55;53;54;55;55;69;53;51 21:25:06 INFO - PROCESS | 28276 | |9;json-stringify-tinderbox;62;63;62;63;64;62;63;63;63;63 21:25:06 INFO - PROCESS | 28276 | |10;stanford-crypto-aes;79;78;78;83;79;79;79;82;79;80 21:25:06 INFO - PROCESS | 28276 | |11;stanford-crypto-ccm;119;118;119;121;119;123;128;122;175;128 21:25:06 INFO - PROCESS | 28276 | |12;stanford-crypto-pbkdf2;161;159;160;157;160;158;155;158;158;157 21:25:06 INFO - PROCESS | 28276 | |13;stanford-crypto-sha256-iterative;63;62;63;63;61;61;62;64;66;63 21:25:06 INFO - PROCESS | 28276 | __end_tp_report 21:25:06 INFO - PROCESS | 28276 | __start_cc_report 21:25:06 INFO - PROCESS | 28276 | _x_x_mozilla_cycle_collect,1689 21:25:06 INFO - PROCESS | 28276 | __end_cc_report 21:25:06 INFO - PROCESS | 28276 | __startTimestamp1464409506249__endTimestamp 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | ------- Summary: start ------- 21:25:06 INFO - PROCESS | 28276 | Number of tests: 14 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.50 stddev:2.02 (1.8%) stddev-sans-first:2.11 21:25:06 INFO - PROCESS | 28276 | Values: 111.0 105.0 110.0 109.0 112.0 112.0 110.0 109.0 110.0 111.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:142.00 stddev:28.21 (19.9%) stddev-sans-first:29.00 21:25:06 INFO - PROCESS | 28276 | Values: 165.0 124.0 126.0 181.0 127.0 125.0 157.0 124.0 199.0 124.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.71 (0.4%) stddev-sans-first:0.73 21:25:06 INFO - PROCESS | 28276 | Values: 161.0 161.0 160.0 161.0 160.0 159.0 161.0 161.0 161.0 160.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.50 stddev:12.96 (17.2%) stddev-sans-first:13.50 21:25:06 INFO - PROCESS | 28276 | Values: 74.0 75.0 76.0 112.0 76.0 76.0 74.0 75.0 97.0 74.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.00 stddev:18.92 (22.8%) stddev-sans-first:19.59 21:25:06 INFO - PROCESS | 28276 | Values: 83.0 82.0 124.0 82.0 83.0 83.0 118.0 84.0 84.0 124.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 21:25:06 INFO - PROCESS | 28276 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 21:25:06 INFO - PROCESS | 28276 | Values: 145.0 147.0 146.0 146.0 145.0 145.0 146.0 145.0 145.0 147.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 21:25:06 INFO - PROCESS | 28276 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:54.50 stddev:5.01 (9.2%) stddev-sans-first:5.29 21:25:06 INFO - PROCESS | 28276 | Values: 54.0 53.0 55.0 53.0 54.0 55.0 55.0 69.0 53.0 51.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 21:25:06 INFO - PROCESS | 28276 | Values: 62.0 63.0 62.0 63.0 64.0 62.0 63.0 63.0 63.0 63.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:1.65 (2.1%) stddev-sans-first:1.73 21:25:06 INFO - PROCESS | 28276 | Values: 79.0 78.0 78.0 83.0 79.0 79.0 79.0 82.0 79.0 80.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:122.50 stddev:17.18 (14.0%) stddev-sans-first:17.96 21:25:06 INFO - PROCESS | 28276 | Values: 119.0 118.0 119.0 121.0 119.0 123.0 128.0 122.0 175.0 128.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.30 Median:158.50 stddev:1.77 (1.1%) stddev-sans-first:1.58 21:25:06 INFO - PROCESS | 28276 | Values: 161.0 159.0 160.0 157.0 160.0 158.0 155.0 158.0 158.0 157.0 21:25:06 INFO - PROCESS | 28276 | 21:25:06 INFO - PROCESS | 28276 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.48 (2.3%) stddev-sans-first:1.56 21:25:06 INFO - PROCESS | 28276 | Values: 63.0 62.0 63.0 63.0 61.0 61.0 62.0 64.0 66.0 63.0 21:25:06 INFO - PROCESS | 28276 | -------- Summary: end -------- 21:25:06 INFO - PROCESS | 28276 | 21:25:07 INFO - TEST-INFO | 28276: exit 0 21:25:07 INFO - TEST-OK | kraken | took 61485ms 21:25:07 INFO - SUITE-END | took 1175s 21:25:07 INFO - Completed test suite (00:19:35) 21:25:07 INFO - javascript benchmark 21:25:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3132.3578666373396, 3134.4358015557204, 3156.4318422683923, 3171.1754943714654, 3186.665400132986, 5357.651752636076, 5414.377346605142, 5465.988792073469, 5504.779808807648, 5528.530074943629, 5415.810446634368, 5427.83716488505, 5466.180072989054, 5488.862778430541, 5521.2270282160425, 4834.033193361333, 4919.458859525467, 4934.876628084291, 4948.604111671076, 5034.370703662043, 4951.207806750921, 4965.280034395028, 4995.350604421438, 5005.049040682259, 5021.422536408305, 4774.546418090275, 4851.151048566487, 4864.902701945941, 4898.436679781835, 4916.459189489154, 4845.103655823648, 4871.683340582851, 4937.851864444073, 4941.135301322254, 4991.401031876198, 4775.785089670973, 4890.486498917598, 4902.2401527762895, 4917.139500587418, 4953.876153096201, 5345.118055520835, 5396.298481197458, 5415.729213539346, 5479.479449452296, 5480.835574932758, 5866.794662186846, 5898.587098903078, 5915.970420147872, 5919.644821310735, 5923.17075609415, 5637.238972738669, 5791.3339965903615, 5805.10020946754, 5813.128030795372, 5818.941810581882, 5466.180072989084, 5577.581681373873, 5580.525655319262, 5586.469285417878, 5587.469190426903, 6038.151355533753, 6068.817935461943, 6077.513798896078, 6084.452399283997, 6091.634501929899, 5616.747246373923, 5697.202391665171, 5742.569307301969, 5805.448482394166, 5822.941770582324, 5868.471837534642, 5891.558133140032, 5900.291964964232, 5901.0, 5910.822675319747, 5801.0, 5844.26946631671, 5849.561282903799, 5849.912251316234, 5851.063829787214, 5588.720563971785, 5752.798652047159, 5801.042827933344, 5803.767849285981, 5884.499817515559, 4416.911661766747, 4418.050119224382, 4421.40311058003, 4425.734455932655, 4456.732596044248, 3925.0187453136714, 3941.684665226775, 3949.960500394959, 3969.7816620085596, 3980.6019398060544, 5405.270288511001, 5469.480399362054, 5476.931998260381, 5502.202180683778, 5511.0, 5838.649681019152, 5867.501262392659, 5877.177195192677, 5880.705964701833, 5938.851528711817, 5531.391546929915, 5532.972335138298, 5537.889242215133, 5545.057340252167, 5546.639468434539, 4896.95103048972, 4992.700437973734, 4998.825041123543, 5005.849824505198, 5014.0, 6022.969885150546, 6032.607880487754, 6100.938990610126, 6103.786367477117, 6105.664188469676, 5590.217369568345, 5905.586608937333, 5911.113333000085, 5917.822465326047, 5919.733611987557, 6021.668808215591, 6059.6364218147055, 6080.179175811386, 6116.26604807426, 6128.1726966858905, 5745.91381129275, 5760.884782304414, 5799.101139323411, 5809.477147056785, 5813.3895940925595, 5639.407862174413, 5795.1017592273265, 5893.852653683521, 5902.6458412495385, 5910.408959104056, 5684.346300175533, 5753.079507278815, 5753.971230143822, 5761.596688231783, 5783.16144159103, 5747.9137812932, 5754.56840736938, 5756.079027355603, 5757.712114394348, 5758.769649213985, 5427.348718153847, 5525.585581081355, 5558.471945165203, 5678.971605141948, 5684.8294551163535, 5745.454181852718, 5770.682612456355, 5775.104858746901, 5785.768569257182, 5786.276715410574, 5565.582581306499, 5599.300087489064, 5605.831825045255, 5610.270664813548, 5653.265075540154], "value": 5379.17614676412, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.9054551271797, 631.5515983651607, 639.6545865232774, 639.6961443314425, 641.080050128066, 32857.17857053572, 36326.45510317347, 42447.151056978844, 43416.348754768704, 43485.13029739411, 44722.77638611811, 45259.0, 45300.320495192514, 45310.0, 45338.54661453384, 8731.126887311282, 8923.063078376774, 8926.86609700855, 8928.687495937644, 8929.107089291085, 8774.517401542913, 8923.598438070303, 8939.865902011476, 8939.92720873496, 8950.328725345593, 8914.197722205, 8934.10658934108, 8981.281497480186, 8985.550722463851, 8986.955065224665, 8859.80392646994, 8969.820603587892, 8976.012638609747, 8976.192142707156, 8977.326700497424, 8753.956230218808, 8855.380123391365, 8855.822883542292, 8857.468551886908, 8866.86699699505, 41980.79009604932, 42350.57649423497, 42417.151656966686, 42479.787601062104, 42594.78702606498, 3759.3797023491093, 3881.611838816124, 4228.830846766125, 4259.339802330613, 4266.466691663542, 32035.51946720801, 32712.67287327144, 32924.34151316984, 33212.169695757555, 33272.6672733272, 4420.226460369455, 4460.77696115193, 4517.164324599959, 4667.15991121598, 4668.253079507296, 354713.0, 355734.0, 357449.0, 357476.0, 358328.9999999974, 61728.38271617316, 62908.68545657242, 63220.994005569395, 63236.68381658154, 63335.683321584016, 63043.36956630467, 63108.0, 63445.0, 63452.0, 63555.68222158952, 17056.658866822843, 17243.913780431016, 17253.0, 17309.480715578553, 17329.960202387898, 12724.618261452171, 12756.61730148097, 12764.425600847984, 12765.234085954873, 12773.0, 16442.66602856394, 18707.0, 18809.02505236958, 18944.0, 19197.424077277705, 5593.440655934455, 6471.417572418411, 6553.180852393451, 6715.529912906148, 6730.865382692319, 1001.6344034427382, 1021.7139201023725, 1036.0279975412889, 1050.1965996013057, 1093.2347356850237, 31792.682073179436, 34119.31761364759, 34218.31563368718, 34218.657813421545, 34320.82839585786, 1092.7978324010085, 1115.8305363313293, 1123.9831402528969, 1140.0, 1180.144395313391, 17425.39011134604, 17708.645827083386, 17853.821461785476, 17912.55218619519, 17941.7308740369, 549535.0, 552924.0, 554246.0, 556209.0, 557144.0, 62175.689121555006, 62811.371886280554, 62818.68590657017, 62922.37077629257, 62999.37000629935, 1231.987680123205, 1260.1179174577833, 1353.2759973414372, 1381.405995421978, 1417.8865690744715, 30940.381192376477, 35119.0, 35146.82426587851, 35266.29467410689, 35346.40289198802, 52220.0, 52718.73640631926, 52812.47187528075, 52888.735556321975, 52919.0, 449400.0, 452419.0, 452456.0, 454024.0, 454548.0, 45065.0, 45713.54286457226, 45849.08301834011, 46362.53637463717, 48578.27132592943, 50312.24531631955, 50476.49523504865, 50836.49163508318, 51338.48661513486, 51847.0, 41511.0, 42108.789456052524, 42653.786731066146, 43367.13265734605, 44253.55746442494, 42554.5744542563, 42737.58099351982, 42797.786011069744, 42860.35709464423, 43530.782346089334, 40647.59352406557, 42002.0, 42752.14495710131, 43051.78474107735, 43096.0, 36376.81811590925, 37186.62813371832, 37293.81353093217, 37727.62272377351, 38248.426273605364, 28249.71750282553, 28431.43137137287, 28602.284942876595, 28684.85657571699, 28693.856530718047], "value": 20865.158824487862, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.46367317591967, 386.98896201937885, 389.929812633726, 401.83725591135584, 406.40258819535273, 18063.36778212763, 18523.2590696372, 19569.217231310748, 19593.70609440858, 19809.60380792383, 18294.634107317845, 18779.812201877976, 19113.426597202073, 19631.60736785267, 19634.509137271576, 5434.864128396792, 5545.973994810973, 5609.1305847593485, 5657.915131273035, 5659.717014149276, 5482.0, 5540.9168862467095, 5541.168824676311, 5595.664260144384, 5601.915971260434, 5463.808766693167, 5476.123820188771, 5557.194206840006, 5577.274954255961, 5591.049521581321, 5529.529989950859, 5562.360328562227, 5572.192032155335, 5586.86032849178, 5587.860303492445, 4761.523847615211, 4915.0, 4921.163402221631, 4932.235503496964, 4940.25896115582, 23001.19495817655, 23311.067557297687, 23329.53340933189, 23452.296431107094, 23720.64419033716, 2356.9528609427716, 2387.8806059697117, 2532.4428625702317, 2579.1746641074865, 2871.7559007484388, 14413.0, 14450.855491445056, 14559.272036398137, 14613.926930365387, 14954.551363459113, 1844.9446516604523, 1963.234338607944, 1986.7814540400545, 2027.3208475160839, 2063.8245749111343, 60614.69692651553, 60854.0, 85507.57246213792, 88775.0, 88965.55517222437, 9421.576029078708, 9582.616695332108, 9704.320697551104, 10278.434686092187, 10755.354678719303, 13591.456341746418, 14420.351084201237, 14421.206833624252, 14451.49419770303, 14925.328360223815, 8193.79515512105, 8220.547869867096, 8226.629801658939, 8270.8345833083, 8569.35729820266, 8127.146649601825, 8190.303824175012, 8196.139405362352, 8277.31659411556, 8305.626246818907, 4524.715018915426, 4529.728216306966, 4562.338460923214, 4569.314602809605, 4651.37206477128, 1883.482042438313, 1885.9811401885806, 1902.7050807124917, 1912.4167129025502, 1915.5402703351374, 856.905740368559, 979.7109852593502, 989.5942663508071, 1028.3521381529588, 1087.8531398261134, 19081.141348639343, 19368.322108726126, 19543.120559574854, 19616.117274722386, 19981.300654477312, 895.6104094718818, 941.5762906692016, 963.3834346018554, 1000.8798944126752, 1039.3088596083671, 10935.398553079656, 11020.834687479693, 11063.336199828036, 11081.445927703582, 11122.721931951604, 498240.0, 504353.0, 556053.0, 559074.0, 561756.0, 95459.52270238605, 99828.0, 102142.4892875531, 103062.0, 103612.0, 1034.6740776655329, 1047.601911273711, 1074.7313171707074, 1083.3987137138963, 1086.6142519405498, 17606.20772065286, 17714.822851771834, 18098.81901180971, 18130.909345453714, 18426.262949482407, 40191.0, 40313.96075490564, 41456.58543414527, 41602.791986039876, 41720.582794172886, 52716.20925686041, 53676.0, 55828.44171558395, 58854.0, 61188.08217876646, 8447.662093516436, 8499.447535910145, 8706.390552661253, 8722.825543489222, 9007.954960225157, 9666.178374837937, 13661.79507307411, 13662.658433539242, 13938.790918136034, 14036.929815351266, 12139.817902731642, 12301.569445069379, 12381.62855114348, 12385.256884586954, 12557.811632825333, 6480.513961453004, 8825.0, 11903.404829758305, 11968.0, 12472.189307694971, 10947.781044378908, 12261.754764904474, 12552.121351505308, 12571.497140114294, 12713.55502557406, 7427.5914824683, 7673.731419400294, 7791.142974272712, 8683.2185103341, 8908.420952638058, 9076.137766912132, 11230.719232019264, 12003.939980300393, 12082.214656047328, 12255.509779608716], "value": 9249.120830048674, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.0633556510445, 1498.7452133137365, 1513.5005448202094, 1686.6542358816444, 1810.0768608009919, 8302.294304984076, 8590.44162129462, 8611.224989750914, 8876.600552975115, 8896.288296936245, 8638.136186381358, 8802.07578204289, 8804.515751633673, 8811.427257228275, 8812.735617931472, 2051.569170474202, 2115.629764791163, 2117.216629846951, 2384.141708984764, 2415.10641062807, 2371.7865392114704, 2441.8046556275453, 2443.1204766284213, 2453.877306134695, 2459.6433517140003, 2370.6799582056374, 2400.603900356439, 2402.423418379585, 2413.5776239158163, 2422.0311875249863, 2077.8753274803407, 2142.4965133193687, 2291.301153148289, 2319.512902290521, 2321.8374713770045, 2094.832413406924, 2291.301153148289, 2348.3659411958674, 2349.97650023501, 2357.3281614739717, 8596.484210947301, 8631.0, 8657.740267791976, 8660.610272537751, 8665.263452606536, 5187.221916712523, 5314.627976041678, 5316.680999140025, 5318.627696061278, 5323.121684921984, 3438.381091403546, 3468.236987862666, 3469.202083520779, 3479.791212527231, 3484.5818501779695, 1103.382106020631, 1124.6457365929707, 1157.6584907452323, 1167.5388221652486, 1180.1797750563362, 11735.295882247092, 11752.23610465317, 11759.882401175964, 11806.350650714206, 11832.0, 4514.232580461329, 4600.88497787558, 4694.976525117352, 4699.95300047002, 4702.388689470416, 10223.028812262823, 10248.180145588334, 10254.230932680079, 10255.692329230134, 10256.28206025586, 5216.295800067019, 5217.452167522431, 5222.060029194781, 5224.8171314004585, 5228.581713462914, 4387.232234359039, 4610.193216187154, 4742.786574604151, 4832.734199619054, 4902.068606964665, 3913.706472014553, 3970.285348637215, 4019.21625283067, 4019.397090436458, 4183.477065366829, 1316.440512782083, 1333.4932725564224, 1337.7926421404698, 1339.1362571141754, 1343.6170691352918, 895.2122132523468, 1021.22897212604, 1030.9278350515392, 1033.9402112834405, 1034.5189486888632, 5809.302883653989, 5969.970150149308, 6007.15899774032, 6175.722092505758, 6182.258129024456, 472.5912086045615, 472.8983268597268, 483.84844071111235, 486.8052778888473, 487.3104557051803, 4223.387608796707, 4225.746455212697, 4226.598473145046, 4234.364845273172, 4245.915081698348, 10536.578536858593, 10872.72818179536, 10987.945060274647, 10993.450327483593, 10997.010269075821, 10835.458227088615, 10848.837267440995, 10852.294600850919, 10878.238523303451, 10883.292585981886, 7793.298603125746, 8047.637856296481, 8096.190380961857, 8227.71203007892, 8346.540940248224, 6097.390260974044, 6210.689465526615, 6250.749970001149, 6371.6814159290925, 6407.359264073555, 9798.461084640414, 9972.700818975442, 10138.543765530421, 10145.543450544596, 10152.340097893613, 10169.237307201842, 10268.794624107326, 10333.586656533655, 10351.275410721177, 10420.68737937863, 7093.787186384417, 7637.579933103732, 7800.219978002155, 7839.490433121829, 7962.800929976797, 8699.608517616596, 8701.782455438664, 8772.824543509221, 8782.209601135928, 8787.25308348772, 7460.440466964891, 7795.766127016199, 7841.764747057369, 7928.246816552649, 8050.959745201237, 7439.776806695808, 7509.136449308399, 7521.586312752632, 7561.848763024819, 7739.264769846855, 7231.05996220509, 7268.745593904188, 7292.96353518229, 7302.342789149002, 7309.853802923805, 5216.582673386044, 5257.658252213594, 5303.946960530346, 5324.1747529133045, 5373.05971454986, 5327.733613319396, 5456.399796022355, 5479.561635069105, 5509.779608815603, 5597.748101335368], "value": 4591.566382639951, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.0518444666002, 319.5590085681759, 321.96941290577394, 323.1887961217345, 324.70289684938285, 5404.621676482643, 5420.473904289917, 5436.565074794017, 5449.536789372908, 5486.231927530149, 5287.762050707718, 5290.68255904646, 5305.806193606437, 5318.1225097858905, 5358.65168764031, 4032.6773858091356, 4046.0, 4200.516940551845, 4218.746875187483, 4223.831046758126, 4124.443200167969, 4177.853775117872, 4182.728122672017, 4196.58034196581, 4199.517055538607, 3959.9604003960026, 4146.502419709624, 4153.1070819773895, 4160.750354978696, 4179.623833854953, 4014.257362387953, 4016.979915100406, 4068.2270368629866, 4118.567550407224, 4124.463819703419, 3627.546556680415, 3640.2173532690335, 3641.3445579795625, 3655.670989610921, 3661.4690869823726, 5096.566791822699, 5141.30592370029, 5143.125668636303, 5148.330717006766, 5188.11801993662, 4552.590266875964, 4678.812847486097, 4707.599854012413, 4731.2430011198385, 4749.240121580565, 5452.509274165344, 5474.315710536183, 5499.972500137514, 5514.91727624086, 5523.260173045518, 4012.8394864205397, 4022.3564229723397, 4024.3561030235023, 4035.1122752994293, 4038.192361527706, 5708.514776244025, 5735.483806457439, 5739.253896993364, 5739.540836733093, 5748.19525266463, 4056.411820286042, 4074.575627501632, 4091.754494730326, 4098.446709694213, 4113.526944401372, 4166.937430955087, 4176.248275310473, 4179.393987871742, 4186.392973018896, 4221.176870510282, 4561.521040290788, 4717.646176536773, 4732.13080833069, 4733.36099626546, 4744.979829336709, 5522.751476183581, 5559.471850174227, 5571.4428557143965, 5583.804566840141, 5590.692511911884, 1447.7683570628649, 1452.869241768246, 1454.549089782171, 1459.9854001459848, 1465.4065103633045, 929.4423345992351, 946.8106378724283, 949.5442187749919, 969.7042402067297, 973.3235401395962, 5356.705381203993, 5370.409254981949, 5390.407055223922, 5395.379531353945, 5413.377461591967, 5733.455321744427, 5738.454846789548, 5749.68376739275, 5754.108113242454, 5767.0, 5219.869503262449, 5380.78476860929, 5384.461553844662, 5394.406615272317, 5400.21696853954, 2990.1926479850317, 3022.6070610820893, 3025.667176610571, 3037.16477968556, 3053.3740583180393, 5640.0, 5854.912176317359, 5896.260603122089, 5921.526277897844, 5924.407559244041, 5684.37471878093, 5716.57125715573, 5778.682172480554, 5787.189793428924, 5809.157672137517, 5786.71066446676, 5821.767129314865, 5866.7066646668345, 5879.823605291848, 5882.735276912549, 5622.85942851432, 5657.971710141423, 5665.49010589049, 5709.743061562157, 5777.884442311046, 5570.498655121059, 5591.804286849941, 5601.887962240814, 5642.407547207484, 5643.830685079454, 5596.468335508121, 5891.764329426775, 5899.882002359843, 5905.52755779528, 5923.259592550931, 4033.213523362916, 4041.474608300902, 4044.5934668871973, 4045.0, 4050.736702114353, 5488.204210389551, 5637.774489020558, 5643.802466913638, 5651.660900345828, 5686.43135686428, 5576.553875690016, 5589.888202236014, 5607.663540187602, 5608.551315894801, 5625.240592519957, 5550.417206193291, 5580.135079062752, 5592.133219351007, 5593.776248949996, 5594.272744543184, 5292.0, 5504.91742623869, 5516.889662206813, 5565.5825813063375, 5591.916121258266, 5636.210930469655, 5679.148127780701, 5686.445971267957, 5714.771409143587, 5717.942820571741, 5472.91790623149, 5597.356304024926, 5604.131359639263, 5624.409437009055, 5650.0], "value": 4252.2434871078185, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.5591424716408, 807.2774866494487, 809.7894547417669, 814.1614137438437, 857.982840343193, 13912.791308130374, 14698.191599462025, 14736.852631473696, 14825.258737063145, 14858.108513489198, 14085.788713169311, 14134.0, 14555.272236388164, 14596.27018649066, 14617.488387906425, 684.284922256241, 824.1717074340295, 826.4297634632114, 828.9419740618159, 830.7839961609994, 794.9165337639552, 819.1439945257221, 819.6967122164807, 825.2778818533783, 825.8348330333957, 856.1310270075878, 856.755824589992, 858.9312854971587, 859.879616853641, 859.9011113721939, 706.7738323736406, 824.2746383182821, 855.6791203298762, 858.1847245117133, 858.549261637639, 615.7075389190144, 617.660286842235, 617.8208319587314, 618.6473709985311, 618.8576627375684, 15473.458428955046, 15494.61263468411, 15500.224988750517, 15522.5343239703, 15527.611809704735, 14323.85676143246, 14441.711165776625, 14510.274486275644, 14512.56462306122, 14685.0, 13140.277284749334, 13143.671408214776, 13168.0, 13359.799603005962, 13978.231197284145, 4526.298423744325, 4545.931811022837, 4562.726236425825, 4576.405067341258, 4739.976300118477, 65654.0, 66251.33748662547, 66810.66594667044, 66826.66586667036, 67372.0, 11604.419779011016, 11675.532978680758, 11816.88183118175, 11845.348505832262, 11877.821832672516, 21380.358589242347, 21484.140634374762, 21503.2473863414, 21518.0, 21555.353339399844, 8502.489850608983, 8589.527575983257, 8676.522791246416, 8679.26226270774, 8728.869066964, 4415.492218394861, 4702.95297047032, 4833.202521583969, 4843.4430040545085, 4907.509249075135, 3555.822208889545, 3649.489071529988, 3657.3233951719017, 3658.140337020798, 3668.706503479715, 662.5262937000068, 669.7722774256777, 674.436845234235, 685.3694600967123, 699.9300069993062, 666.6466772610427, 737.3253473072186, 741.4550305525435, 755.7883792538098, 765.7090305683804, 9515.619375224913, 9854.260930430102, 10045.397276163454, 10140.898591014144, 10262.743431414274, 628.7987843889912, 666.8657310640011, 708.7950484176816, 768.7078910014159, 770.6416516319938, 4846.0, 4963.329950456714, 5029.748512574357, 5030.8239211627415, 5061.898762024813, 65846.0, 68732.65633671799, 69898.0, 70945.0, 79698.6015069921, 58641.0, 58692.70653646704, 59088.40911590915, 59909.0, 60845.39154608397, 53968.46031539634, 54355.45644543583, 54687.45312546824, 55276.447235527936, 55830.72084639632, 8930.298407760878, 9694.709158725249, 9856.42064708045, 10083.344582602107, 10118.49407529606, 37797.0, 37847.40449657278, 37877.431838521894, 38181.23637527179, 38372.42441363438, 50845.237321440945, 51099.74450127726, 51321.743391282806, 51728.224076639635, 52199.47800522098, 12956.0, 12978.351082446028, 12992.610221693365, 13084.869151308745, 13545.32273386307, 14219.360128794437, 14732.55802325932, 15003.549893503212, 15513.76729349038, 15518.844811552191, 13117.278549679859, 13379.197248165141, 13543.932280338535, 13882.0, 14151.929240353733, 12932.517778109528, 13083.803742943674, 13620.31898405056, 13981.0, 14218.573442796733, 12525.311107889154, 12560.811587826007, 12568.87431125677, 12742.554010609585, 13152.408141633841, 10905.405270463314, 11190.667979354916, 11453.484593193492, 11538.192326537062, 11710.297382157098, 11122.721931951442, 11223.0, 11338.773224535627, 11370.147238956946, 11457.484413201259], "value": 7212.83601347921, "unit": "score"}], "name": "dromaeo_css", "value": 7258.188275568933, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 109.0, 112.0, 112.0, 110.0, 109.0, 110.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 124.0, 126.0, 181.0, 127.0, 125.0, 157.0, 124.0, 199.0, 124.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 159.0, 161.0, 161.0, 161.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 112.0, 76.0, 76.0, 74.0, 75.0, 97.0, 74.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 124.0, 82.0, 83.0, 83.0, 118.0, 84.0, 84.0, 124.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 53.0, 54.0, 55.0, 55.0, 69.0, 53.0, 51.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 64.0, 62.0, 63.0, 63.0, 63.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 83.0, 79.0, 79.0, 79.0, 82.0, 79.0, 80.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 119.0, 121.0, 119.0, 123.0, 128.0, 122.0, 175.0, 128.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 157.0, 160.0, 158.0, 155.0, 158.0, 158.0, 157.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 63.0, 61.0, 61.0, 62.0, 64.0, 66.0, 63.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1494.8, "alertThreshold": 2.0}]} 21:25:07 INFO - Return code: 0 21:25:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:25:07 ERROR - # TBPL SUCCESS # 21:25:07 INFO - Running post-action listener: _resource_record_post_action 21:25:07 INFO - ##### 21:25:07 INFO - ##### Finished run-tests step (success) 21:25:07 INFO - ##### 21:25:07 INFO - Running post-run listener: _resource_record_post_run 21:25:07 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 538636288; Read time: 12; Write time: 583564 21:25:07 INFO - TinderboxPrint: CPU usage
13.2% 21:25:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 21:25:07 INFO - TinderboxPrint: I/O write bytes / time
538,636,288 / 583,564 21:25:07 INFO - TinderboxPrint: CPU idle
8,267.0 (86.8%) 21:25:07 INFO - TinderboxPrint: CPU user
1,208.0 (12.7%) 21:25:07 INFO - TinderboxPrint: Swap in / out
0 / 0 21:25:07 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 237780992; Read time: 0; Write time: 330784 21:25:07 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291135488; Read time: 12; Write time: 251512 21:25:08 INFO - Running post-run listener: _upload_blobber_files 21:25:08 INFO - Blob upload gear active. 21:25:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:25:08 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 21:25:08 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:25:08 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:25:08 INFO - (blobuploader) - INFO - Open directory for files ... 21:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:25:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:12 INFO - (blobuploader) - INFO - Done attempting. 21:25:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:25:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:25:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:13 INFO - (blobuploader) - INFO - Done attempting. 21:25:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:25:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:25:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:14 INFO - (blobuploader) - INFO - Done attempting. 21:25:14 INFO - (blobuploader) - INFO - Iteration through files over. 21:25:14 INFO - Return code: 0 21:25:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:25:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:25:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/095695107c2d5b55081b0b70c1e29a0093b9a6cd811e27afd63eb3ba4841110b3f4bb11a0df0ac39df20f2b7469ee3b474c95ad923ccdc612323a41543fffebd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/673902495912fd5dcd0b8dd3813ecb32fc4a97755db56198ad9ea7a2089548bedb849d359d06b3af0825fda949083593b83e4bb1493df6dae18af60a3aa9ec80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cd56ab83fadf6034742a6b28cd04a9d888edb96f46b499376805cc69e501e7e7c88a00af2b331dca980643ea89c8dd10b0d500e25295e596efc94184ed93807b"} 21:25:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:25:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:25:14 INFO - Contents: 21:25:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/095695107c2d5b55081b0b70c1e29a0093b9a6cd811e27afd63eb3ba4841110b3f4bb11a0df0ac39df20f2b7469ee3b474c95ad923ccdc612323a41543fffebd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/673902495912fd5dcd0b8dd3813ecb32fc4a97755db56198ad9ea7a2089548bedb849d359d06b3af0825fda949083593b83e4bb1493df6dae18af60a3aa9ec80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cd56ab83fadf6034742a6b28cd04a9d888edb96f46b499376805cc69e501e7e7c88a00af2b331dca980643ea89c8dd10b0d500e25295e596efc94184ed93807b"} 21:25:14 INFO - Running post-run listener: copy_logs_to_upload_dir 21:25:14 INFO - Copying logs to upload dir... 21:25:14 INFO - mkdir: /builds/slave/test/build/upload/logs 21:25:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.119992 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-27 21:25:12.991357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 21:25:12.994890) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/095695107c2d5b55081b0b70c1e29a0093b9a6cd811e27afd63eb3ba4841110b3f4bb11a0df0ac39df20f2b7469ee3b474c95ad923ccdc612323a41543fffebd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/673902495912fd5dcd0b8dd3813ecb32fc4a97755db56198ad9ea7a2089548bedb849d359d06b3af0825fda949083593b83e4bb1493df6dae18af60a3aa9ec80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cd56ab83fadf6034742a6b28cd04a9d888edb96f46b499376805cc69e501e7e7c88a00af2b331dca980643ea89c8dd10b0d500e25295e596efc94184ed93807b"} build_url:https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://queue.taskcluster.net/v1/task/D5gSrQTDT427sXIo4JJOaw/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/Larch/sha512/095695107c2d5b55081b0b70c1e29a0093b9a6cd811e27afd63eb3ba4841110b3f4bb11a0df0ac39df20f2b7469ee3b474c95ad923ccdc612323a41543fffebd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/673902495912fd5dcd0b8dd3813ecb32fc4a97755db56198ad9ea7a2089548bedb849d359d06b3af0825fda949083593b83e4bb1493df6dae18af60a3aa9ec80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cd56ab83fadf6034742a6b28cd04a9d888edb96f46b499376805cc69e501e7e7c88a00af2b331dca980643ea89c8dd10b0d500e25295e596efc94184ed93807b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 21:25:13.019575) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:25:13.019867) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1464408117.547736-991041754 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004558 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:25:13.102231) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 21:25:13.102555) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 21:25:13.102831) ========= ========= Total master_lag: 0.15 =========