builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1463267492.78 results: success (0) buildid: 20160514133002 builduid: 958b083be23a4962b05c5f688c8c95cf revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.784506) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.785070) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.785352) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.802381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.802666) ========= 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=62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-14 16:11:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.9M=0s 2016-05-14 16:11:33 (42.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092774 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.908628) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.908933) ========= 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=62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019070 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:32.957845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 16:11:32.958163) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 403912ca555eb65f814b18ecf38ad8e8e98569f5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 403912ca555eb65f814b18ecf38ad8e8e98569f5 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-14 16:11:33,597 truncating revision to first 12 chars 2016-05-14 16:11:33,597 Setting DEBUG logging. 2016-05-14 16:11:33,598 attempt 1/10 2016-05-14 16:11:33,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/403912ca555e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 16:11:34,880 unpacking tar archive at: mozilla-central-403912ca555e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.558058 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 16:11:34.530785) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:34.531220) ========= script_repo_revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:34.531776) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:34.532163) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 16:11:34.546897) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-05-14 16:11:34.547386) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:11:35 INFO - MultiFileLogger online at 20160514 16:11:35 in /builds/slave/test 16:11:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:11:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:11:35 INFO - {'append_to_log': False, 16:11:35 INFO - 'base_work_dir': '/builds/slave/test', 16:11:35 INFO - 'blob_upload_branch': 'Firefox', 16:11:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:11:35 INFO - 'branch': 'Firefox', 16:11:35 INFO - 'buildbot_json_path': 'buildprops.json', 16:11:35 INFO - 'config_files': ('talos/linux_config.py',), 16:11:35 INFO - 'default_actions': ('clobber', 16:11:35 INFO - 'read-buildbot-config', 16:11:35 INFO - 'download-and-extract', 16:11:35 INFO - 'populate-webroot', 16:11:35 INFO - 'create-virtualenv', 16:11:35 INFO - 'install', 16:11:35 INFO - 'run-tests'), 16:11:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:11:35 INFO - 'download_minidump_stackwalk': True, 16:11:35 INFO - 'download_symbols': 'ondemand', 16:11:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:11:35 INFO - 'tooltool.py': '/tools/tooltool.py', 16:11:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:11:35 INFO - '/tools/misc-python/virtualenv.py')}, 16:11:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:11:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:11:35 INFO - 'installer_path': 'installer.exe', 16:11:35 INFO - 'log_level': 'info', 16:11:35 INFO - 'log_name': 'talos', 16:11:35 INFO - 'log_to_console': True, 16:11:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:11:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:11:35 INFO - 'opt_config_files': (), 16:11:35 INFO - 'pip_index': False, 16:11:35 INFO - 'sps_profile': False, 16:11:35 INFO - 'sps_profile_interval': 0, 16:11:35 INFO - 'suite': 'dromaeojs', 16:11:35 INFO - 'system_bits': '32', 16:11:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:11:35 INFO - 'title': 'talos-linux64-ix-018', 16:11:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:11:35 INFO - 'use_talos_json': True, 16:11:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:11:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:11:35 INFO - 'work_dir': 'build'} 16:11:35 INFO - ##### 16:11:35 INFO - ##### Running clobber step. 16:11:35 INFO - ##### 16:11:35 INFO - Running pre-action listener: _resource_record_pre_action 16:11:35 INFO - Running main action method: clobber 16:11:35 INFO - rmtree: /builds/slave/test/build 16:11:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:11:35 INFO - Running post-action listener: _resource_record_post_action 16:11:35 INFO - ##### 16:11:35 INFO - ##### Finished clobber step (success) 16:11:35 INFO - ##### 16:11:35 INFO - ##### 16:11:35 INFO - ##### Running read-buildbot-config step. 16:11:35 INFO - ##### 16:11:35 INFO - Running pre-action listener: _resource_record_pre_action 16:11:35 INFO - Running main action method: read_buildbot_config 16:11:35 INFO - Using buildbot properties: 16:11:35 INFO - { 16:11:35 INFO - "project": "", 16:11:35 INFO - "product": "firefox", 16:11:35 INFO - "script_repo_revision": "production", 16:11:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:11:35 INFO - "repository": "", 16:11:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 16:11:35 INFO - "buildid": "20160514133002", 16:11:35 INFO - "pgo_build": "True", 16:11:35 INFO - "basedir": "/builds/slave/test", 16:11:35 INFO - "buildnumber": 442, 16:11:35 INFO - "slavename": "talos-linux64-ix-018", 16:11:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:11:35 INFO - "platform": "ubuntu64_hw", 16:11:35 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 16:11:35 INFO - "branch": "mozilla-central", 16:11:35 INFO - "repo_path": "mozilla-central", 16:11:35 INFO - "revision": "403912ca555eb65f814b18ecf38ad8e8e98569f5", 16:11:35 INFO - "stage_platform": "linux64-pgo", 16:11:35 INFO - "builduid": "958b083be23a4962b05c5f688c8c95cf", 16:11:35 INFO - "slavebuilddir": "test" 16:11:35 INFO - } 16:11:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:11:35 INFO - Running post-action listener: _resource_record_post_action 16:11:35 INFO - ##### 16:11:35 INFO - ##### Finished read-buildbot-config step (success) 16:11:35 INFO - ##### 16:11:35 INFO - ##### 16:11:35 INFO - ##### Running download-and-extract step. 16:11:35 INFO - ##### 16:11:35 INFO - Running pre-action listener: _resource_record_pre_action 16:11:35 INFO - Running main action method: download_and_extract 16:11:35 INFO - mkdir: /builds/slave/test/build/tests 16:11: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')]} 16:11:35 INFO - https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:11:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:11:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:11:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:41 INFO - Downloaded 1361 bytes. 16:11:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:11:41 INFO - Using the following test package requirements: 16:11:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:11:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:11:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:11:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'jsshell-linux-x86_64.zip'], 16:11:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:11:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:11:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:11:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:11:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:11:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:11:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:11:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:41 INFO - https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:11:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:11:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:11:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:47 INFO - Downloaded 24023644 bytes. 16:11:47 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'] 16:11:47 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 16:11:48 INFO - Return code: 0 16:11:48 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 16:11:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:48 INFO - https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:11:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:11:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:11:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:50 INFO - Downloaded 24023644 bytes. 16:11:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:11:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:11:51 INFO - Return code: 0 16:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:51 INFO - https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:11:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:11:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:11:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:52 INFO - Downloaded 10840194 bytes. 16:11:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:11:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:11:53 INFO - Return code: 0 16:11:53 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')]} 16:11:53 INFO - https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:11:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:11:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:11:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eHKmPmcwTUS6ka-D4vfz5A/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 16:11:54 INFO - Downloaded 58396911 bytes. 16:11:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:11:54 INFO - mkdir: /builds/slave/test/properties 16:11:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:11:54 INFO - Writing to file /builds/slave/test/properties/build_url 16:11:54 INFO - Contents: 16:11:54 INFO - build_url:https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:11:55 INFO - Running post-action listener: _resource_record_post_action 16:11:55 INFO - Running post-action listener: set_extra_try_arguments 16:11:55 INFO - ##### 16:11:55 INFO - ##### Finished download-and-extract step (success) 16:11:55 INFO - ##### 16:11:55 INFO - ##### 16:11:55 INFO - ##### Running populate-webroot step. 16:11:55 INFO - ##### 16:11:55 INFO - Running pre-action listener: _resource_record_pre_action 16:11:55 INFO - Running main action method: populate_webroot 16:11:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:11:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:11:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:11:55 INFO - '--tppagecycles', 16:11:55 INFO - '7'], 16:11:55 INFO - 'tests': ['tsvgm']}}, 16:11:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:11:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:11:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:11:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:11:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['damp', 'tps']}, 16:11:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'tests': ['damp', 'tps']}, 16:11:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['dromaeo_dom']}, 16:11:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:11:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['a11yr', 16:11:55 INFO - 'ts_paint', 16:11:55 INFO - 'tpaint', 16:11:55 INFO - 'sessionrestore', 16:11:55 INFO - 'sessionrestore_no_auto_restore', 16:11:55 INFO - 'tabpaint']}, 16:11:55 INFO - 'other-e10s': {'tests': ['a11yr', 16:11:55 INFO - 'ts_paint', 16:11:55 INFO - 'tpaint', 16:11:55 INFO - 'sessionrestore', 16:11:55 INFO - 'sessionrestore_no_auto_restore', 16:11:55 INFO - 'tabpaint']}, 16:11:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:11:55 INFO - 'ts_paint', 16:11:55 INFO - 'tpaint', 16:11:55 INFO - 'sessionrestore', 16:11:55 INFO - 'sessionrestore_no_auto_restore', 16:11:55 INFO - 'tabpaint']}, 16:11:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:11:55 INFO - 'ts_paint', 16:11:55 INFO - 'tpaint', 16:11:55 INFO - 'sessionrestore', 16:11:55 INFO - 'sessionrestore_no_auto_restore', 16:11:55 INFO - 'tabpaint']}, 16:11:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['a11yr', 16:11:55 INFO - 'ts_paint', 16:11:55 INFO - 'tpaint', 16:11:55 INFO - 'sessionrestore', 16:11:55 INFO - 'sessionrestore_no_auto_restore', 16:11:55 INFO - 'tabpaint']}, 16:11:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['a11yr', 16:11:55 INFO - 'ts_paint', 16:11:55 INFO - 'tpaint', 16:11:55 INFO - 'sessionrestore', 16:11:55 INFO - 'sessionrestore_no_auto_restore', 16:11:55 INFO - 'tabpaint']}, 16:11:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['tsvgx', 16:11:55 INFO - 'tsvgr_opacity', 16:11:55 INFO - 'tart', 16:11:55 INFO - 'tscrollx', 16:11:55 INFO - 'cart']}, 16:11:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:11:55 INFO - 'tsvgr_opacity', 16:11:55 INFO - 'tart', 16:11:55 INFO - 'tscrollx', 16:11:55 INFO - 'cart']}, 16:11:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'talos_options': ['--disable-e10s'], 16:11:55 INFO - 'tests': ['tp5o']}, 16:11:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'tests': ['tp5o']}, 16:11:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'talos_options': ['--disable-e10s', 16:11:55 INFO - '--xperf_path', 16:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:55 INFO - 'tests': ['tp5n']}, 16:11:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:55 INFO - 'talos_options': ['--xperf_path', 16:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:55 INFO - 'tests': ['tp5n']}}, 16:11:55 INFO - 'talos.zip': {'path': '', 16:11:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:11:55 INFO - Running post-action listener: _resource_record_post_action 16:11:55 INFO - ##### 16:11:55 INFO - ##### Finished populate-webroot step (success) 16:11:55 INFO - ##### 16:11:55 INFO - ##### 16:11:55 INFO - ##### Running create-virtualenv step. 16:11:55 INFO - ##### 16:11:55 INFO - Running pre-action listener: _resource_record_pre_action 16:11:55 INFO - Running main action method: create_virtualenv 16:11:55 INFO - Creating virtualenv /builds/slave/test/build/venv 16:11:55 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 16:11:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:11:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:11:55 INFO - Using real prefix '/usr' 16:11:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:11:55 INFO - Installing distribute.............................................................................................................................................................................................done. 16:11:57 INFO - Installing pip.................done. 16:11:57 INFO - Return code: 0 16:11:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:11: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', 'pip>=1.5'] in /builds/slave/test/build 16:11: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 pip>=1.5 16:11:57 INFO - Using env: {'DISPLAY': ':0', 16:11:57 INFO - 'HOME': '/home/cltbld', 16:11:57 INFO - 'LANG': 'en_US.UTF-8', 16:11:57 INFO - 'LANGUAGE': 'en_US:en', 16:11:57 INFO - 'LOGNAME': 'cltbld', 16:11:57 INFO - 'MAIL': '/var/mail/cltbld', 16:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:57 INFO - 'MOZ_NO_REMOTE': '1', 16:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:57 INFO - 'NO_EM_RESTART': '1', 16:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:57 INFO - 'PWD': '/builds/slave/test', 16:11:57 INFO - 'SHELL': '/bin/bash', 16:11:57 INFO - 'SHLVL': '1', 16:11:57 INFO - 'TERM': 'linux', 16:11:57 INFO - 'TMOUT': '86400', 16:11:57 INFO - 'USER': 'cltbld', 16:11:57 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:57 INFO - '_': '/tools/buildbot/bin/python'} 16:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:57 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 16:11:57 INFO - Cleaning up... 16:11:58 INFO - Return code: 0 16:11:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:58 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:11:58 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 16:11:58 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 16:11:58 INFO - Using env: {'DISPLAY': ':0', 16:11:58 INFO - 'HOME': '/home/cltbld', 16:11:58 INFO - 'LANG': 'en_US.UTF-8', 16:11:58 INFO - 'LANGUAGE': 'en_US:en', 16:11:58 INFO - 'LOGNAME': 'cltbld', 16:11:58 INFO - 'MAIL': '/var/mail/cltbld', 16:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:58 INFO - 'MOZ_NO_REMOTE': '1', 16:11:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:58 INFO - 'NO_EM_RESTART': '1', 16:11:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:58 INFO - 'PWD': '/builds/slave/test', 16:11:58 INFO - 'SHELL': '/bin/bash', 16:11:58 INFO - 'SHLVL': '1', 16:11:58 INFO - 'TERM': 'linux', 16:11:58 INFO - 'TMOUT': '86400', 16:11:58 INFO - 'USER': 'cltbld', 16:11:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:58 INFO - '_': '/tools/buildbot/bin/python'} 16:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:58 INFO - Downloading/unpacking psutil>=3.1.1 16:11:58 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 16:11:58 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 16:11:58 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 16:11:58 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 16:11:58 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 16:11:58 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 16:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:00 INFO - Installing collected packages: psutil 16:12:00 INFO - Running setup.py install for psutil 16:12:00 INFO - building 'psutil._psutil_linux' extension 16:12:00 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 16:12:00 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 16:12:00 INFO - building 'psutil._psutil_posix' extension 16:12:00 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 16:12:00 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 16:12:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:00 INFO - Successfully installed psutil 16:12:00 INFO - Cleaning up... 16:12:00 INFO - Return code: 0 16:12:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:00 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:12:00 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 16:12:00 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 16:12:00 INFO - Using env: {'DISPLAY': ':0', 16:12:00 INFO - 'HOME': '/home/cltbld', 16:12:00 INFO - 'LANG': 'en_US.UTF-8', 16:12:00 INFO - 'LANGUAGE': 'en_US:en', 16:12:00 INFO - 'LOGNAME': 'cltbld', 16:12:00 INFO - 'MAIL': '/var/mail/cltbld', 16:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:00 INFO - 'MOZ_NO_REMOTE': '1', 16:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:00 INFO - 'NO_EM_RESTART': '1', 16:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:00 INFO - 'PWD': '/builds/slave/test', 16:12:00 INFO - 'SHELL': '/bin/bash', 16:12:00 INFO - 'SHLVL': '1', 16:12:00 INFO - 'TERM': 'linux', 16:12:00 INFO - 'TMOUT': '86400', 16:12:00 INFO - 'USER': 'cltbld', 16:12:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:00 INFO - '_': '/tools/buildbot/bin/python'} 16:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:12:02 INFO - Installing collected packages: mozsystemmonitor 16:12:02 INFO - Running setup.py install for mozsystemmonitor 16:12:03 INFO - Successfully installed mozsystemmonitor 16:12:03 INFO - Cleaning up... 16:12:03 INFO - Return code: 0 16:12:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:03 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:12:03 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 16:12:03 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 16:12:03 INFO - Using env: {'DISPLAY': ':0', 16:12:03 INFO - 'HOME': '/home/cltbld', 16:12:03 INFO - 'LANG': 'en_US.UTF-8', 16:12:03 INFO - 'LANGUAGE': 'en_US:en', 16:12:03 INFO - 'LOGNAME': 'cltbld', 16:12:03 INFO - 'MAIL': '/var/mail/cltbld', 16:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:03 INFO - 'MOZ_NO_REMOTE': '1', 16:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:03 INFO - 'NO_EM_RESTART': '1', 16:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:03 INFO - 'PWD': '/builds/slave/test', 16:12:03 INFO - 'SHELL': '/bin/bash', 16:12:03 INFO - 'SHLVL': '1', 16:12:03 INFO - 'TERM': 'linux', 16:12:03 INFO - 'TMOUT': '86400', 16:12:03 INFO - 'USER': 'cltbld', 16:12:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:03 INFO - '_': '/tools/buildbot/bin/python'} 16:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:03 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:03 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 16:12:03 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 16:12:03 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 16:12:03 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 16:12:03 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 16:12:03 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 16:12:05 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:12:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12:05 INFO - Downloading docopt-0.6.1.tar.gz 16:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:05 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:05 INFO - Running setup.py install for blobuploader 16:12:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:12:06 INFO - Running setup.py install for requests 16:12:06 INFO - Running setup.py install for docopt 16:12:06 INFO - Successfully installed blobuploader requests docopt 16:12:06 INFO - Cleaning up... 16:12:06 INFO - Return code: 0 16:12:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:06 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:12:06 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 16:12:06 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 16:12:06 INFO - Using env: {'DISPLAY': ':0', 16:12:06 INFO - 'HOME': '/home/cltbld', 16:12:06 INFO - 'LANG': 'en_US.UTF-8', 16:12:06 INFO - 'LANGUAGE': 'en_US:en', 16:12:06 INFO - 'LOGNAME': 'cltbld', 16:12:06 INFO - 'MAIL': '/var/mail/cltbld', 16:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:06 INFO - 'MOZ_NO_REMOTE': '1', 16:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:06 INFO - 'NO_EM_RESTART': '1', 16:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:06 INFO - 'PWD': '/builds/slave/test', 16:12:06 INFO - 'SHELL': '/bin/bash', 16:12:06 INFO - 'SHLVL': '1', 16:12:06 INFO - 'TERM': 'linux', 16:12:06 INFO - 'TMOUT': '86400', 16:12:06 INFO - 'USER': 'cltbld', 16:12:06 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:06 INFO - '_': '/tools/buildbot/bin/python'} 16:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:06 INFO - Running setup.py (path:/tmp/pip-tqVs75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:06 INFO - Running setup.py (path:/tmp/pip-XpMSRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:06 INFO - Running setup.py (path:/tmp/pip-Sol6Q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:06 INFO - Running setup.py (path:/tmp/pip-elVCBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:07 INFO - Running setup.py (path:/tmp/pip-8vNsU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:07 INFO - Running setup.py (path:/tmp/pip-bceoWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:07 INFO - Running setup.py (path:/tmp/pip-9E551w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:07 INFO - Running setup.py (path:/tmp/pip-gTkWdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:07 INFO - Running setup.py (path:/tmp/pip-Z4oX3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:07 INFO - Running setup.py (path:/tmp/pip-BK2NYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:07 INFO - Running setup.py (path:/tmp/pip-kfz_JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:07 INFO - Running setup.py (path:/tmp/pip-CcTJ2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:07 INFO - Running setup.py (path:/tmp/pip-VdgXrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:07 INFO - Running setup.py (path:/tmp/pip-5cYnnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:07 INFO - Running setup.py (path:/tmp/pip-o4LSyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:07 INFO - Running setup.py (path:/tmp/pip-XhoDrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:07 INFO - Running setup.py (path:/tmp/pip-r2cx7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:07 INFO - Running setup.py install for manifestparser 16:12:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Running setup.py install for mozcrash 16:12:08 INFO - Running setup.py install for mozdebug 16:12:08 INFO - Running setup.py install for mozdevice 16:12:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Running setup.py install for mozfile 16:12:08 INFO - Running setup.py install for mozhttpd 16:12:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Running setup.py install for mozinfo 16:12:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Running setup.py install for mozInstall 16:12:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Running setup.py install for mozleak 16:12:08 INFO - Running setup.py install for mozlog 16:12:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:12:08 INFO - Running setup.py install for moznetwork 16:12:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:12:09 INFO - Running setup.py install for mozprocess 16:12:09 INFO - Running setup.py install for mozprofile 16:12:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:12:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:12:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:12:09 INFO - Running setup.py install for mozrunner 16:12:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:12:09 INFO - Running setup.py install for mozscreenshot 16:12:09 INFO - Running setup.py install for moztest 16:12:09 INFO - Running setup.py install for mozversion 16:12:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:12:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:12:09 INFO - Cleaning up... 16:12:09 INFO - Return code: 0 16:12:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:09 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:12:09 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 16:12:09 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 16:12:09 INFO - Using env: {'DISPLAY': ':0', 16:12:09 INFO - 'HOME': '/home/cltbld', 16:12:09 INFO - 'LANG': 'en_US.UTF-8', 16:12:09 INFO - 'LANGUAGE': 'en_US:en', 16:12:09 INFO - 'LOGNAME': 'cltbld', 16:12:09 INFO - 'MAIL': '/var/mail/cltbld', 16:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:09 INFO - 'MOZ_NO_REMOTE': '1', 16:12:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:09 INFO - 'NO_EM_RESTART': '1', 16:12:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:09 INFO - 'PWD': '/builds/slave/test', 16:12:09 INFO - 'SHELL': '/bin/bash', 16:12:09 INFO - 'SHLVL': '1', 16:12:09 INFO - 'TERM': 'linux', 16:12:09 INFO - 'TMOUT': '86400', 16:12:09 INFO - 'USER': 'cltbld', 16:12:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:09 INFO - '_': '/tools/buildbot/bin/python'} 16:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:09 INFO - Running setup.py (path:/tmp/pip-uPz8ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:10 INFO - Running setup.py (path:/tmp/pip-Y2_9qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:10 INFO - Running setup.py (path:/tmp/pip-F_oCpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:10 INFO - Running setup.py (path:/tmp/pip-iBC2Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:10 INFO - Running setup.py (path:/tmp/pip-j41VVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:10 INFO - Running setup.py (path:/tmp/pip-kgauvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:10 INFO - Running setup.py (path:/tmp/pip-pNtauX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:10 INFO - Running setup.py (path:/tmp/pip-tnt5Cw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:10 INFO - Running setup.py (path:/tmp/pip-wl941W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:10 INFO - Running setup.py (path:/tmp/pip-7kohGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:10 INFO - Running setup.py (path:/tmp/pip-00muNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:10 INFO - Running setup.py (path:/tmp/pip-_Jt66y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:10 INFO - Running setup.py (path:/tmp/pip-UCHJLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:10 INFO - Running setup.py (path:/tmp/pip-Ys0CbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:10 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)) 16:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:10 INFO - Running setup.py (path:/tmp/pip-7M_cvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:11 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)) 16:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:11 INFO - Running setup.py (path:/tmp/pip-z5PvWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:11 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)) 16:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:11 INFO - Running setup.py (path:/tmp/pip-wYyavM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:11 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)) 16:12:11 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)) 16:12:11 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)) 16:12:11 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)) 16:12:11 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)) 16:12:11 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)) 16:12:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12:13 INFO - Downloading blessings-1.6.tar.gz 16:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:12:13 INFO - Installing collected packages: blessings 16:12:13 INFO - Running setup.py install for blessings 16:12:13 INFO - Successfully installed blessings 16:12:13 INFO - Cleaning up... 16:12:13 INFO - Return code: 0 16:12:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:13 INFO - Reading from file tmpfile_stdout 16:12:13 INFO - Current package versions: 16:12:13 INFO - argparse == 1.2.1 16:12:13 INFO - blessings == 1.6 16:12:13 INFO - blobuploader == 1.2.4 16:12:13 INFO - docopt == 0.6.1 16:12:13 INFO - manifestparser == 1.1 16:12:13 INFO - mozInstall == 1.12 16:12:13 INFO - mozcrash == 0.17 16:12:13 INFO - mozdebug == 0.1 16:12:13 INFO - mozdevice == 0.48 16:12:13 INFO - mozfile == 1.2 16:12:13 INFO - mozhttpd == 0.7 16:12:13 INFO - mozinfo == 0.9 16:12:13 INFO - mozleak == 0.1 16:12:13 INFO - mozlog == 3.2 16:12:13 INFO - moznetwork == 0.27 16:12:13 INFO - mozprocess == 0.22 16:12:13 INFO - mozprofile == 0.28 16:12:13 INFO - mozrunner == 6.11 16:12:13 INFO - mozscreenshot == 0.1 16:12:13 INFO - mozsystemmonitor == 0.3 16:12:13 INFO - moztest == 0.7 16:12:13 INFO - mozversion == 1.4 16:12:13 INFO - psutil == 3.1.1 16:12:13 INFO - requests == 1.2.3 16:12:13 INFO - wsgiref == 0.1.2 16:12:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:13 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:12:13 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 16:12:13 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 16:12:13 INFO - Using env: {'DISPLAY': ':0', 16:12:13 INFO - 'HOME': '/home/cltbld', 16:12:13 INFO - 'LANG': 'en_US.UTF-8', 16:12:13 INFO - 'LANGUAGE': 'en_US:en', 16:12:13 INFO - 'LOGNAME': 'cltbld', 16:12:13 INFO - 'MAIL': '/var/mail/cltbld', 16:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:13 INFO - 'MOZ_NO_REMOTE': '1', 16:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:13 INFO - 'NO_EM_RESTART': '1', 16:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:13 INFO - 'PWD': '/builds/slave/test', 16:12:13 INFO - 'SHELL': '/bin/bash', 16:12:13 INFO - 'SHLVL': '1', 16:12:13 INFO - 'TERM': 'linux', 16:12:13 INFO - 'TMOUT': '86400', 16:12:13 INFO - 'USER': 'cltbld', 16:12:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:13 INFO - '_': '/tools/buildbot/bin/python'} 16:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 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)) 16:12:13 INFO - Cleaning up... 16:12:13 INFO - Return code: 0 16:12:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:13 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:12:13 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 16:12:13 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 16:12:13 INFO - Using env: {'DISPLAY': ':0', 16:12:13 INFO - 'HOME': '/home/cltbld', 16:12:13 INFO - 'LANG': 'en_US.UTF-8', 16:12:13 INFO - 'LANGUAGE': 'en_US:en', 16:12:13 INFO - 'LOGNAME': 'cltbld', 16:12:13 INFO - 'MAIL': '/var/mail/cltbld', 16:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:13 INFO - 'MOZ_NO_REMOTE': '1', 16:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:13 INFO - 'NO_EM_RESTART': '1', 16:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:13 INFO - 'PWD': '/builds/slave/test', 16:12:13 INFO - 'SHELL': '/bin/bash', 16:12:13 INFO - 'SHLVL': '1', 16:12:13 INFO - 'TERM': 'linux', 16:12:13 INFO - 'TMOUT': '86400', 16:12:13 INFO - 'USER': 'cltbld', 16:12:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:13 INFO - '_': '/tools/buildbot/bin/python'} 16:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:13 INFO - Downloading/unpacking jsonschema 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:12:15 INFO - Downloading/unpacking functools32 (from jsonschema) 16:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:16 INFO - Downloading functools32-3.2.3-2.tar.gz 16:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:12:16 INFO - warning: no files found matching '*.txt' 16:12:16 INFO - no previously-included directories found matching 'build' 16:12:16 INFO - no previously-included directories found matching 'dist' 16:12:16 INFO - no previously-included directories found matching '.git*' 16:12:16 INFO - Installing collected packages: jsonschema, functools32 16:12:16 INFO - Running setup.py install for functools32 16:12:16 INFO - warning: no files found matching '*.txt' 16:12:16 INFO - no previously-included directories found matching 'build' 16:12:16 INFO - no previously-included directories found matching 'dist' 16:12:16 INFO - no previously-included directories found matching '.git*' 16:12:16 INFO - Successfully installed jsonschema functools32 16:12:16 INFO - Cleaning up... 16:12:16 INFO - Return code: 0 16:12:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:12:16 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')]} 16:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:16 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')]} 16:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:16 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 0x7ff69357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, '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': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', '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 16:12:16 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 16:12:16 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 16:12:16 INFO - Using env: {'DISPLAY': ':0', 16:12:16 INFO - 'HOME': '/home/cltbld', 16:12:16 INFO - 'LANG': 'en_US.UTF-8', 16:12:16 INFO - 'LANGUAGE': 'en_US:en', 16:12:16 INFO - 'LOGNAME': 'cltbld', 16:12:16 INFO - 'MAIL': '/var/mail/cltbld', 16:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:16 INFO - 'MOZ_NO_REMOTE': '1', 16:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:16 INFO - 'NO_EM_RESTART': '1', 16:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:16 INFO - 'PWD': '/builds/slave/test', 16:12:16 INFO - 'SHELL': '/bin/bash', 16:12:16 INFO - 'SHLVL': '1', 16:12:16 INFO - 'TERM': 'linux', 16:12:16 INFO - 'TMOUT': '86400', 16:12:16 INFO - 'USER': 'cltbld', 16:12:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:16 INFO - '_': '/tools/buildbot/bin/python'} 16:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:16 INFO - Downloading/unpacking flake8 16:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:12:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:12:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:18 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 16:12:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:12:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:12:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:12:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:12:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:12:19 INFO - no previously-included directories found matching 'docs/_build' 16:12:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:12:19 INFO - Running setup.py install for pep8 16:12:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:12:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:12:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:12:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:12:19 INFO - no previously-included directories found matching 'docs/_build' 16:12:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:12:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:12:19 INFO - Cleaning up... 16:12:19 INFO - Return code: 0 16:12:19 INFO - Running post-action listener: _resource_record_post_action 16:12:19 INFO - Running post-action listener: _start_resource_monitoring 16:12:19 INFO - Starting resource monitoring. 16:12:19 INFO - ##### 16:12:19 INFO - ##### Finished create-virtualenv step (success) 16:12:19 INFO - ##### 16:12:19 INFO - ##### 16:12:19 INFO - ##### Running install step. 16:12:19 INFO - ##### 16:12:19 INFO - Running pre-action listener: _resource_record_pre_action 16:12:19 INFO - Running main action method: install 16:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:19 INFO - Reading from file tmpfile_stdout 16:12:19 INFO - Detecting whether we're running mozinstall >=1.0... 16:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:12:19 INFO - Reading from file tmpfile_stdout 16:12:19 INFO - Output received: 16:12:19 INFO - Usage: mozinstall [options] installer 16:12:19 INFO - Options: 16:12:19 INFO - -h, --help show this help message and exit 16:12:19 INFO - -d DEST, --destination=DEST 16:12:19 INFO - Directory to install application into. [default: 16:12:19 INFO - "/builds/slave/test"] 16:12:19 INFO - --app=APP Application being installed. [default: firefox] 16:12:19 INFO - mkdir: /builds/slave/test/build/application 16:12:19 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'] 16:12:19 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 16:12:36 INFO - Reading from file tmpfile_stdout 16:12:36 INFO - Output received: 16:12:36 INFO - /builds/slave/test/build/application/firefox/firefox 16:12:36 INFO - Running post-action listener: _resource_record_post_action 16:12:36 INFO - ##### 16:12:36 INFO - ##### Finished install step (success) 16:12:36 INFO - ##### 16:12:36 INFO - ##### 16:12:36 INFO - ##### Running run-tests step. 16:12:36 INFO - ##### 16:12:36 INFO - Running pre-action listener: _resource_record_pre_action 16:12:36 INFO - Running main action method: run_tests 16:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:12:36 INFO - Python 2.7.3 16:12:36 INFO - Return code: 0 16:12:36 INFO - grabbing minidump binary from tooltool 16:12:36 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')]} 16:12:36 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 0x298a9c0>, '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 0x298b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:12:36 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 16:12:36 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 16:12:36 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 16:12:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:12:36 INFO - Return code: 0 16:12:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:12:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:12:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:12:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:12:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:12:37 INFO - Return code: 0 16:12:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 16:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 16:12:37 INFO - Using env: {'DISPLAY': ':0', 16:12:37 INFO - 'HOME': '/home/cltbld', 16:12:37 INFO - 'LANG': 'en_US.UTF-8', 16:12:37 INFO - 'LANGUAGE': 'en_US:en', 16:12:37 INFO - 'LOGNAME': 'cltbld', 16:12:37 INFO - 'MAIL': '/var/mail/cltbld', 16:12:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:12:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:37 INFO - 'MOZ_NO_REMOTE': '1', 16:12:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:37 INFO - 'NO_EM_RESTART': '1', 16:12:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:37 INFO - 'PWD': '/builds/slave/test', 16:12:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:12:37 INFO - 'SHELL': '/bin/bash', 16:12:37 INFO - 'SHLVL': '1', 16:12:37 INFO - 'TERM': 'linux', 16:12:37 INFO - 'TMOUT': '86400', 16:12:37 INFO - 'USER': 'cltbld', 16:12:37 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879', 16:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:37 INFO - '_': '/tools/buildbot/bin/python'} 16:12:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 16:12:37 INFO - mozversion application_buildid: 20160514133002 16:12:37 INFO - mozversion application_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 16:12:37 INFO - mozversion application_display_name: Nightly 16:12:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:12:37 INFO - mozversion application_name: Firefox 16:12:37 INFO - mozversion application_remotingname: firefox 16:12:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:12:37 INFO - mozversion application_vendor: Mozilla 16:12:37 INFO - mozversion application_version: 49.0a1 16:12:37 INFO - mozversion platform_buildid: 20160514133002 16:12:37 INFO - mozversion platform_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 16:12:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:12:37 INFO - mozversion platform_version: 49.0a1 16:12:37 INFO - using testdate: 1463267557 16:12:37 INFO - actual date: 1463267557 16:12:37 INFO - starting webserver on 'localhost:43397' 16:12:37 INFO - SUITE-START | Running 2 tests 16:12:37 INFO - TEST-START | dromaeo_css 16:12:37 INFO - operating with platform_type : linux_ 16:12:37 INFO - Initialising browser for dromaeo_css test... 16:12:37 INFO - TEST-INFO | started process 9869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYaoPIm/profile http://localhost:43397/getInfo.html) 16:12:41 INFO - PROCESS | 9869 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 16:12:41 INFO - PROCESS | 9869 | __metrics Screen width/height:1600/1200 16:12:41 INFO - PROCESS | 9869 | colorDepth:24 16:12:41 INFO - PROCESS | 9869 | Browser inner width/height: 1024/697 16:12:41 INFO - PROCESS | 9869 | __metrics 16:12:42 ERROR - PROCESS | 9869 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:12:43 INFO - TEST-INFO | 9869: exit 0 16:12:43 INFO - Browser initialized. 16:12:43 INFO - Running cycle 1/1 for dromaeo_css test... 16:12:43 INFO - TEST-INFO | started process 9952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYaoPIm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:55 INFO - PROCESS | 9952 | RSS: Main: 152436736 16:12:55 INFO - PROCESS | 9952 | 16:13:46 INFO - PROCESS | 9952 | 1463267626305 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] 16:15:54 INFO - PROCESS | 9952 | Cycle 1(1): loaded http://localhost:43397/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43397/tests/dromaeo/cssquery-ext.html) 16:15:55 INFO - PROCESS | 9952 | RSS: Main: 243437568 16:15:55 INFO - PROCESS | 9952 | 16:18:57 INFO - PROCESS | 9952 | Cycle 1(1): loaded http://localhost:43397/tests/dromaeo/cssquery-ext.html (next: http://localhost:43397/tests/dromaeo/cssquery-jquery.html) 16:18:57 INFO - PROCESS | 9952 | RSS: Main: 243953664 16:18:57 INFO - PROCESS | 9952 | 16:21:59 INFO - PROCESS | 9952 | Cycle 1(1): loaded http://localhost:43397/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43397/tests/dromaeo/cssquery-mootools.html) 16:22:00 INFO - PROCESS | 9952 | RSS: Main: 252993536 16:22:00 INFO - PROCESS | 9952 | 16:25:02 INFO - PROCESS | 9952 | Cycle 1(1): loaded http://localhost:43397/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43397/tests/dromaeo/cssquery-prototype.html) 16:25:02 INFO - PROCESS | 9952 | RSS: Main: 237596672 16:25:02 INFO - PROCESS | 9952 | 16:28:05 INFO - PROCESS | 9952 | Cycle 1(1): loaded http://localhost:43397/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43397/tests/dromaeo/cssquery-yui.html) 16:28:06 INFO - PROCESS | 9952 | RSS: Main: 214401024 16:28:06 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | Cycle 1(1): loaded http://localhost:43397/tests/dromaeo/cssquery-yui.html (next: http://localhost:43397/tests/dromaeo/cssquery-dojo.html) 16:31:09 INFO - PROCESS | 9952 | RSS: Main: 269910016 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | __start_tp_report 16:31:09 INFO - PROCESS | 9952 | _x_x_mozilla_page_load 16:31:09 INFO - PROCESS | 9952 | _x_x_mozilla_page_load_details 16:31:09 INFO - PROCESS | 9952 | |i|pagename|runs| 16:31:09 INFO - PROCESS | 9952 | |0;dojo.html;3118.2048577612704;3186.856591453384;3210.438173319669;3219.919502012448;3236.821974791387;5239.947600523994;5301;5399.2441058251825;5501.119820828668;5530.032244357231;5425.403205647375;5427.158790387486;5427.185922111686;5449.891002179954;5509.504144626982;4854.781534830932;4910.2143657014885;4972;4977.178765503706;5122.769475373599;4970.527799859007;4993.725345106018;5015.648904576681;5048.242763585455;5048.368953880765;4831.033793241349;4877.804887804501;4890.266460031024;4898.706077635317;4904.043711476245;4883.951160488421;4895.388076490439;4967.180415231483;4982.427020892607;5010.273510341017;4731.597814185799;4933.876653083666;4934.309196712463;4968.850934471971;4971.428285747149;5337.092694241949;5382.165764306539;5397.4062853086125;5466.070767969415;5490.450954904518;5880.26496687914;5889.204957330791;5901.970490147564;5902.2327097477055;5931.970340148272;5667.178259152441;5715.228444160027;5722.799702010409;5916.822495325147;5933.139694744238;5400.675959442407;5421.783128674848;5425.565954723613;5436.293281873332;5442.510174084351;5910.8817823643285;5947.643141411442;5954.732037058343;5957.76168953237;5957.791477298274;5551.278333816578;5600.411956744565;5605.411431799684;5694.715264236772;5717.199592057115;5745.482906538432;5808.332041815161;5820.970895145497;5837.678927658934;5844.1233814927255;5818.4763371296785;5819.563532734977;5820.35976042635;5824.097264923944;5840.678762668094;5528.585356098309;5680.403557626472;5768.221290125779;5826.650400975955;5840.707964601753;4348.652107831366;4374.868753937387;4390.3414487827085;4396.12077584478;4428.535003824616;3898.0449789801114;3905.2384784967226;3913.002184442949;3914.4911161548816;3955.4857868476915;5343.450399384135;5411;5452.781888724407;5497.752601132958;5509.862253443615;5839.883202335929;5848.766049358063;5884.676342801102;5901.17383566301;5942.64344139353;5518.2826232590305;5549.833504994856;5553.888922221533;5563.554915606742;5567.860803479945;4855.6601037927;4938.037082768898;4951.777170027285;5016.222485514678;5077.923831142536;5953.642781433128;5981.102834574762;5988.191594134823;5989.460948514655;6053.152558641794;5780.884382312414;5807.738651760766;5808.186853840381;5808.593398462236;5809.099589563624;5748.223989761496;5900.321463031806;5982.790602328898;6017.518598512021;6043.8186854394435;5719.428057194247;5781.537477001913;5786.42135786418;5794.507466865195;5794.507466865363;5894.528437724886;5894.616849904743;5903.203067585992;5908.822735317947;5908.822735317947;5504.0643090675485;5607.747651355618;5613.607047506799;5655.632383895034;5697.971510142423;5665.801696940587;5672.2058911751565;5677.943220567741;5679.77280908759;5681.4034526374135;5414.75633596497;5584.804531841366;5602.355729091207;5605.971970140286;5606.915896261641;5621.128725047624;5661.688607126647;5698.6010979232715;5780.3352614448195;5792;5610.18652295423;5614.438556144353;5617.157426386075;5624.606277560531;5669.574781891292 16:31:09 INFO - PROCESS | 9952 | |1;ext.html;639.1946147853704;647.5111290975312;649.5777744466096;665.873484038033;665.8967859981705;41348.37977430341;43183.13633727324;43290;47228;47621.28568071482;44898.32652510215;45804.312935306;45819.31271030937;45869.770651146784;45926;8669.393142480014;8886.955565222197;8889.199972002518;8901.821963560726;8901.999999999967;8768.430052046597;8880.8667869982;8880.8667869982;8883.466991980502;8887.15572020657;8828.690995815148;8930.687425940094;8936.821263574724;8949.552522373888;8958.910410895905;8801.031886492481;8809.471431714086;8846.911530884674;8910.331725120643;8913.732588022338;8781.473111613324;8878.28973682104;8887.688930887452;8916.108389161096;8918.821623567557;41363.793181034205;41575.79212103951;41876.79061604703;41889.58110418918;41911;3797.8290976906105;3807.2195199983935;4037.899052523681;4054.493188351456;4389.714668546534;32096.679033209602;32505;32628.184295392566;32655.6734432655;33736.1565960853;4337.544557821415;4388.978055109704;4403.845865394728;4470.642348612103;4542.704724192917;353808;354382.22808886046;355691;356430;356637;54271.72864135733;54585.181222281695;54635.726821365635;54790;54924;60212.69893650504;60570;60822;61368;61454.69272653608;16443.506694799176;16638.25127869249;16743.832561674226;16757.664846703243;16802.243899024572;12769.106162568716;12808.6797830055;12819.230846149261;12828.55100071493;12831.486740530274;14621.853781462261;15024.323905424282;15038.097714137186;15253.542393728207;15358.769618455732;5494.36814766299;6113.938860611337;6194.845128871724;6247.75008999644;6634.369734875179;1064.5794911010107;1119.4682525800342;1172;1191.3745283726114;1239.7148655809035;30216.84891575528;30269.69730302713;30376.240593984887;30674.38651226963;30884.76460941537;1159.263867444179;1168.2406435816617;1170.5259369955181;1179.257068047125;1194.0030074887566;16572.917135414245;16587.585310367336;16699.244624636798;16810.57973550671;16888;531145;534189;536743;537358;538248;58232.708836455546;58371.41628583745;58599.4140058594;58642.41357586455;58716.70641646764;1340.4102195034154;1393.7351903138372;1474.6313421644588;1475.284487023814;1488.0699562773266;32368.190795230308;32621.18447038843;33671.663283366855;33787.457828934705;34129.31741365109;49660.503394965584;49814.750926245135;49893;49945;50689.74655126701;427285;427703;427962;428680;429363;30654.693453066076;33274.500882487264;35826.64173358233;37539;39613.207735845695;41428.79285603553;45709.542904571856;46665.06669866652;46893.29660055033;47813.5218647823;39305.803470982464;39308.213835722556;39729.2054158921;39823.40264895971;40585.391219132325;38179.42730858984;38207.426888596136;38532.42201366926;38735.80632096934;39058.21883562256;40126.39810402788;40295.194096117324;41246.793766030976;41602.37596436116;42190.57809421867;34924.12689682778;36418.65574752864;36554.81722591476;36827.79033257906;37234;27982.3004424891;28317.71682283151;28350.858245708638;28682;28857 16:31:09 INFO - PROCESS | 9952 | |2;jquery.html;385.65098585779873;388.4173739390912;394.34735512726445;395.3042644944897;417.78484080698445;14110.929445352758;17529.91235043823;17615.295388184473;19169.233230670772;19237.615247695037;17265.136743162853;18291.268349266047;18810.717839232424;19290.51773705658;19327.51681207967;5492.395836457987;5513.8345849624575;5609.719514024282;5672.5178359839465;5684.118961560965;4010.358342665174;5610.326760788711;5618.971905140468;5638.182463542784;5917.378675239102;6171.13604095427;6284.528660350469;6304.495640348761;6312.968435157818;6315.147455093573;6024.518038556905;6251.781187658433;6288.213973253343;6288.465480434145;6292.811215663537;5543.750531226105;5549.5837812164245;5551.861203469905;5552.611317207797;5574.637648552831;21198.5760284795;22367.664485032576;23188.608683479044;23191.101112360953;23326.300210993526;2579.200447861169;2640.4719056188646;2766.4250270974485;2797.38457539341;3119.142235885127;14301.213433261166;14607;14608.612181842707;14850.108993460426;15011.924940375338;1972.8125828046313;1986.1757370691146;1995.2717258200798;2048.4162013826135;2138.700581918533;82249;84677.15322846755;85270.57364713137;85847.57076214641;86840.56579717124;11053;11188.160887933438;11280.887191128148;11512;11774.293542387484;14222.288885555681;14559.708805823824;16338.591535211715;16340.509784706477;16373.035235771784;7872.881906771403;7949.562774047483;8092;8166.959165204136;8193.713220037389;7362.3741981932135;7403.629818509053;7410.777676669709;7537.283958023979;7574.962125189339;4833.806647734052;4847.854564363075;4851.636127290467;4862.610991120702;4895.853124406202;1965.5774008588223;2005.3883565512658;2018.576099019193;2031.9695204571942;2043.468698138465;932.3007744191856;985.6008316631776;1000.3997601439224;1001.0490034467285;1031.7626945802508;18450.815491845176;18999.24003039863;19379;19404.32084877051;19452.124654390587;981.3277904635347;1026.645807196516;1054.8101341758404;1055.134789472625;1069.983515660126;10379.325343852626;10739.624113156002;10806.243562950518;10924.399158046384;10928.180386471047;481108;486367;496318;496715;505265;90718.54640726886;91285;91558.54220728854;92712.53643731738;95057;1055.5461151704687;1080.0711388206137;1105.4141305108305;1116.1517246892258;1117.402189828454;17158.22787974569;17373.913130434266;17388.82611173872;17550.73673894918;17634.294628214728;26593.867030665497;36962;38533.42199866948;38602.80698596489;39374;55473;57861.4213857856;58664.413355867604;59140.40859591349;59296.11055834252;8710.738677839676;8891.510966896623;8911.420757650732;8969.955150224207;9156.542172891463;10073.546690398804;13645.522406716114;13959.790603141164;14135.293235338399;14178.14931104137;12147.453364598437;12224.44989975471;12323.9383803084;12407.875921240671;12417.813732794197;11732;11852.288862668267;11861.347625880659;12018.459169337226;12196.75606487883;6485.578437401554;11321.320720756781;12043.313936049135;12051.276923384625;12257.203281786655;8966.955165224132;9064.864027039732;9098.408603440756;9207.815843683224;9271.6291348348;11406.82889756671;11677.598688157475;11679.591214307458;11737.530498779954;12057.397130143283 16:31:09 INFO - PROCESS | 9952 | |3;mootools.html;1419.666378401075;1477.9926100369496;1550.1164336328293;1632.183908045977;1749.6063385738203;8600.010998735148;8916.777080572982;8930.464172149668;9164.679236226717;9176.816463670739;8883.289336853053;9063.592138353773;9072.546372681356;9093.545322733853;9108.362414630978;2445.877706114696;2471.5921872890954;2472.9752702473015;2474.208253358926;2474.4556197636493;2453.141400509825;2513.962290565643;2523.3817714659936;2524.684414448194;2524.9747502525015;2442.438239204984;2443.1571107967725;2481.627755836621;2488.664030355897;2491.190363131989;2358.0921345282018;2358.8466749661216;2375.5961486547244;2430.6232533957264;2431.6960379952507;2291.163725240292;2345.9296221113395;2347.7534858839745;2352.5765362234793;2354.281944007077;7536.095668519758;8658.090900455487;8968.641254349819;9070.410423322463;9078.86381704275;5464.644798088151;5686.459786320293;5686.744096515667;5689.459501347406;5690.573207009491;3395.7622966392364;3492.912677183065;3530.1527633368064;3537.557805274341;3546.343926373603;1116.743149075709;1126.5212284779018;1133.1501373969522;1178.7524619829846;1180.1090176916373;13183.538576149884;13240.139390939557;13354.465821367134;13367.131136476099;13377.464901403931;4771.6400825764495;4799.520047995173;4803.663743537919;4815.759212039454;4907.631927605373;10248.180145588334;10256.07695307426;10265.538050787733;10268.97310268982;10277.691669249934;5562.276904002454;5602.831915042555;5620;5624.690642014729;5626.578006649518;5129.589632829365;5245.763940622681;5257;5271.947280527223;5327.86680332987;3805.7060599396336;3823.713221508398;3884.708022266876;3934.724569280123;3950.7234493585747;1150.71232191952;1153.1985270237271;1160.4835848047708;1168.4449886304108;1190.8451901252781;961.2118063188119;972.4019727867452;1010.56545685356;1025.3847691385108;1043.175891046065;6113.57204995655;6205.410486003822;6249.500039996789;6258.530610204253;6314.747410103544;472.1430603454719;479.3815977389129;493.77286448234213;494.1327969413656;496.5183771741477;4276.294411422081;4326.459192600925;4357.150355680612;4357.172137293904;4364.192624364503;11272.943635281772;11689.824652630217;11696.70758231051;11699.649010529698;11719.882801172049;11528.250663706833;11610.593629223105;11628.88371116278;11633.825492617618;11648.53405863773;8591.226789588842;8758.167974042455;8759.518226497476;8764.605592748341;8769.166929141722;6128.754849805958;6163.506919446344;6168;6291.087792270187;6350.936490635219;10089.545970431202;10206.132478739093;10431.426271555138;10465.738356541147;10858.34849909008;10818.837717434088;10910.127189824954;11076.5569377224;11097.223194376316;11107.38909359993;8158.836823263383;8269.917300826915;8310.501369917823;8338.666453341799;8354;8773.078826723102;8810.823783524165;8888.64445422176;8922.241609463008;8924.86612700823;8180.7954801130445;8224.259816616533;8247.752567422987;8318.085010648703;8480.236778689947;8096.757097287091;8121.918780812352;8139.918600813916;8243.505389676639;8296.543690097102;7100.147982242164;7248.855022899407;7301.853962920818;7314.780556583311;7324.157721862054;4956.182229932021;5325.946740532545;5432.7555260012605;5487.122060470306;5524.723763811874;5407;5473.480019398151;5492.368377636463;5601.131824567198;5662.716864156858 16:31:09 INFO - PROCESS | 9952 | |4;prototype.html;333.68966860819427;341.02636971446515;349.88803582853495;350.8964855367665;353.73116431512045;5224.48489937918;5317.59083842782;5330.92003619946;5344.808628462326;5361.892762144755;5173.689578625285;5195.58435325174;5223.190405487156;5261.657992230512;5262.81580144695;4003.4195041718935;4163.646090082347;4163.979180104103;4167.437395951553;4172.853950111747;4037.3540233562635;4172.685604034735;4186.492862569473;4187.476565429321;4197.895052623693;4103.610157035092;4121.526024507175;4129.876103716878;4139.882231797413;4165.791710414483;3995.180987897474;4063.6952228582677;4127.463429754142;4133.627973482401;4187.790610469464;3602;3617.9457308140136;3690.15126520899;3694.556653201606;3701.7038636908983;5094.617903657204;5121.129408000612;5133.435322114565;5142.305788718512;5152.742362881879;4603.838865639686;4769.236922092483;4770.189067858472;4774.307725379836;4786.305985632064;5480.287562616834;5488.69812160331;5496.285482887239;5507.421720719347;5520.227168196456;4042.241624893181;4042.535108462506;4050.25070361984;4056.634902858757;4074.022234663659;5530.5299049580835;5742.588112992553;5775.797847075374;5798.768049278066;5825.242718446575;4103.958960410418;4108.5275193352545;4109.938350924738;4122.299209134454;4126.442930204444;4177.310743727312;4179.331306990927;4183.142455796555;4187.14163596462;4198.580141985777;4725.929111063337;4751.501092385319;4761.142994261067;4770.022145460172;4805.111054454937;5230.92153617696;5557.471695283829;5569.27599412082;5571.637843540157;5571.637843540157;1409.6194027612487;1428.649980754701;1435.2823588205897;1438.8992770505963;1445.8771004464738;903.5405510731263;914.5198770645464;917.1012407840353;920.9271199053162;934.6168071090821;4783.282507623884;5228.346456692913;5239.161734122522;5342;5354.4472103089665;5622.85942851432;5630.268065151504;5641.689707066069;5666.518345940642;5676.829695109153;5190.97404512975;5330.413654498002;5347.01080300138;5393.5954803389905;5405.972970135203;2974.1523665755158;2985.8357790321306;3001.264690150927;3004.9549256761165;3005.9398812024074;5746.82190151018;5847.736851841677;5861.3552509223955;5871.266091738533;5881.264841894763;5522.91715624266;5617.41017193197;5670.489655930986;5721.656700597978;5765.509931655856;5747.482726554546;5789.3921138279875;5797.536197104221;5816.709164541756;5843.211166492554;5435.211894275387;5581.6930068846605;5643.463870932255;5646.689432081275;5681.403452637579;5581.7209139543675;5591.440855914376;5600.719964001865;5603.32760068778;5604.551635869039;5741.555066959791;5762.2221000164445;5768.807853015827;5810.4189581041555;5825.563082768894;3894.3768996961485;3972.781497017692;3990.880273591797;4017.116234428421;4117.517693630351;5604.327480702342;5643.463870932255;5646.2942132233475;5652.180433836988;5677.432256774289;5520.282363292747;5532.723363831873;5596.720163991703;5597.496225339576;5610.4670056344585;5388.315193037357;5508.173773933942;5557.5831812613405;5567.8886422272135;5601.131824567198;5421.5933804964;5430.755615997368;5465.890682186414;5469.644473109235;5479.53423958968;5385.838424847261;5485.177223416519;5496.790706044664;5498.780048798003;5622.465865742748;5310.336207974004;5443.564514838883;5445;5449.945500545103;5469.917951230815 16:31:09 INFO - PROCESS | 9952 | |5;yui.html;819.3076850061698;819.5656302159856;845.754731127973;849.2017503546666;850.4854562989395;15029.32368043438;15896.84103158968;15986.600334991632;16021.118838463879;16037.35850565976;14548.854511454882;14585.051971621837;14611.19638419889;14616.049956752804;14621.195834229113;694.2502097734434;822.9876551851727;825.2531459029587;848.3594885861166;852.2074470742207;820.0118856777599;829.2288172000068;832.9083800781909;833.0669649992018;837.4221786967004;866.6879923227633;868.8349213649418;869.4348673362333;872.8254349130168;873.5370253765515;729.5002922997737;872.3021582733819;898.128815049401;898.5013317608757;901.5762591581946;652.1847690387016;655.0861548140317;657.4740207833762;657.510154934572;657.5167252069725;13866.4453421863;13933.164010159422;13960.720785584333;13986.370613322424;14016.088954217943;13072.150310229805;13092.083554151213;14125.646858828612;14279.428822847074;14369.281535923163;11174.161937854693;11346.943265283704;11673.649790506299;11739.647810565697;11777.94111029448;4444.266695995157;4490.59584637381;4517.525659805706;4664.860054198345;4673.672842900998;57464.138037929464;58346.12480812791;58353.70823145899;58695.11957320644;58957;11258.887411125948;11384.032357249727;11409.5790857456;11440.713982150513;11491.885081149248;19896.204151833765;20168.89915550413;20448.591028179355;20454.181832726525;20514.076866541043;8345.582720863933;8622.137786221454;8717.433366831263;8761.649534018696;8926.68756593516;4714.764261786897;4760.619150467955;4800.255960326155;4895.265710143507;4921.8523444296725;3578.624244454347;3650.926981460409;3686.7972261525338;3690.7785532868115;3700.925981480409;622.411820829312;660.7092879133166;672.4821887146869;745.0798264143779;751.620431681997;661.9768308109193;679.2324673119343;763.5800309829572;771.9189485104096;772.7140957845521;9159.45043297391;9627.133557979816;9731.221502279801;9791.755206119904;9913.603455861685;640.3276559612389;720.5676594043532;751.9097708274933;771;786.6066966516742;4512.300593408027;4566.566176213254;4570.611498022638;4579.519150489217;4614.261718125151;66750;69181.3081869185;69341;75149;76325.23674763292;59251.40748592459;60053.099203511985;61034.694826525585;61891.690541547905;62729.68635156886;55183.72408137934;55766.442335576125;56356.71821640948;56748.148777768365;56817.43182568121;9254.907450925404;9464.337496375463;9467.526623668928;9479.383840050376;9532.237421006164;34662.82668586641;35114.824425877705;36039;37361.4395784058;38084.238315232986;50790.492095078574;50828.745856270485;51595.484045159064;51825;52911.73544132255;13016.739665206833;13128;13137.73724525485;13353.39909704085;13769.173849569059;14234.288285585886;14296.642583935485;14512.637184070483;14695.706085878437;15843.762343564626;10636.627718029831;13438.814140022103;13786.379612917242;14184.361703723564;14360.210188439736;13229.536966206137;13752;13793.517226897011;14212.786808197678;14502.202378869264;13787.241701706502;13907.58277251684;14042.1574705518;14559.781603276171;14662;11400.258983166068;11505.424728763695;11660;11736.591609006973;12009.75980480368;11559.653210403701;11801.940990294994;12018.465456860928;12111.75776484483;12244.387780611112 16:31:09 INFO - PROCESS | 9952 | __end_tp_report 16:31:09 INFO - PROCESS | 9952 | __start_cc_report 16:31:09 INFO - PROCESS | 9952 | _x_x_mozilla_cycle_collect,799 16:31:09 INFO - PROCESS | 9952 | __end_cc_report 16:31:09 INFO - PROCESS | 9952 | __startTimestamp1463268669296__endTimestamp 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | ------- Summary: start ------- 16:31:09 INFO - PROCESS | 9952 | Number of tests: 6 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | [#0] dojo.html Cycles:165 Average:5393.51 Median:5605.41 stddev:614.98 (11.0%) stddev-sans-first:590.39 16:31:09 INFO - PROCESS | 9952 | Values: 3118.2 3186.9 3210.4 3219.9 3236.8 5239.9 5301.0 5399.2 5501.1 5530.0 5425.4 5427.2 5427.2 5449.9 5509.5 4854.8 4910.2 4972.0 4977.2 5122.8 4970.5 4993.7 5015.6 5048.2 5048.4 4831.0 4877.8 4890.3 4898.7 4904.0 4884.0 4895.4 4967.2 4982.4 5010.3 4731.6 4933.9 4934.3 4968.9 4971.4 5337.1 5382.2 5397.4 5466.1 5490.5 5880.3 5889.2 5902.0 5902.2 5932.0 5667.2 5715.2 5722.8 5916.8 5933.1 5400.7 5421.8 5425.6 5436.3 5442.5 5910.9 5947.6 5954.7 5957.8 5957.8 5551.3 5600.4 5605.4 5694.7 5717.2 5745.5 5808.3 5821.0 5837.7 5844.1 5818.5 5819.6 5820.4 5824.1 5840.7 5528.6 5680.4 5768.2 5826.7 5840.7 4348.7 4374.9 4390.3 4396.1 4428.5 3898.0 3905.2 3913.0 3914.5 3955.5 5343.5 5411.0 5452.8 5497.8 5509.9 5839.9 5848.8 5884.7 5901.2 5942.6 5518.3 5549.8 5553.9 5563.6 5567.9 4855.7 4938.0 4951.8 5016.2 5077.9 5953.6 5981.1 5988.2 5989.5 6053.2 5780.9 5807.7 5808.2 5808.6 5809.1 5748.2 5900.3 5982.8 6017.5 6043.8 5719.4 5781.5 5786.4 5794.5 5794.5 5894.5 5894.6 5903.2 5908.8 5908.8 5504.1 5607.7 5613.6 5655.6 5698.0 5665.8 5672.2 5677.9 5679.8 5681.4 5414.8 5584.8 5602.4 5606.0 5606.9 5621.1 5661.7 5698.6 5780.3 5792.0 5610.2 5614.4 5617.2 5624.6 5669.6 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | [#1] ext.html Cycles:180 Average:60042.64 Median:41589.08 stddev:118166.64 (284.1%) stddev-sans-first:118413.96 16:31:09 INFO - PROCESS | 9952 | Values: 639.2 647.5 649.6 665.9 665.9 41348.4 43183.1 43290.0 47228.0 47621.3 44898.3 45804.3 45819.3 45869.8 45926.0 8669.4 8887.0 8889.2 8901.8 8902.0 8768.4 8880.9 8880.9 8883.5 8887.2 8828.7 8930.7 8936.8 8949.6 8958.9 8801.0 8809.5 8846.9 8910.3 8913.7 8781.5 8878.3 8887.7 8916.1 8918.8 41363.8 41575.8 41876.8 41889.6 41911.0 3797.8 3807.2 4037.9 4054.5 4389.7 32096.7 32505.0 32628.2 32655.7 33736.2 4337.5 4389.0 4403.8 4470.6 4542.7 353808.0 354382.2 355691.0 356430.0 356637.0 54271.7 54585.2 54635.7 54790.0 54924.0 60212.7 60570.0 60822.0 61368.0 61454.7 16443.5 16638.3 16743.8 16757.7 16802.2 12769.1 12808.7 12819.2 12828.6 12831.5 14621.9 15024.3 15038.1 15253.5 15358.8 5494.4 6113.9 6194.8 6247.8 6634.4 1064.6 1119.5 1172.0 1191.4 1239.7 30216.8 30269.7 30376.2 30674.4 30884.8 1159.3 1168.2 1170.5 1179.3 1194.0 16572.9 16587.6 16699.2 16810.6 16888.0 531145.0 534189.0 536743.0 537358.0 538248.0 58232.7 58371.4 58599.4 58642.4 58716.7 1340.4 1393.7 1474.6 1475.3 1488.1 32368.2 32621.2 33671.7 33787.5 34129.3 49660.5 49814.8 49893.0 49945.0 50689.7 427285.0 427703.0 427962.0 428680.0 429363.0 30654.7 33274.5 35826.6 37539.0 39613.2 41428.8 45709.5 46665.1 46893.3 47813.5 39305.8 39308.2 39729.2 39823.4 40585.4 38179.4 38207.4 38532.4 38735.8 39058.2 40126.4 40295.2 41246.8 41602.4 42190.6 34924.1 36418.7 36554.8 36827.8 37234.0 27982.3 28317.7 28350.9 28682.0 28857.0 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | [#2] jquery.html Cycles:180 Average:29165.44 Median:12952.75 stddev:81339.40 (628.0%) stddev-sans-first:81538.88 16:31:09 INFO - PROCESS | 9952 | Values: 385.7 388.4 394.3 395.3 417.8 14110.9 17529.9 17615.3 19169.2 19237.6 17265.1 18291.3 18810.7 19290.5 19327.5 5492.4 5513.8 5609.7 5672.5 5684.1 4010.4 5610.3 5619.0 5638.2 5917.4 6171.1 6284.5 6304.5 6313.0 6315.1 6024.5 6251.8 6288.2 6288.5 6292.8 5543.8 5549.6 5551.9 5552.6 5574.6 21198.6 22367.7 23188.6 23191.1 23326.3 2579.2 2640.5 2766.4 2797.4 3119.1 14301.2 14607.0 14608.6 14850.1 15011.9 1972.8 1986.2 1995.3 2048.4 2138.7 82249.0 84677.2 85270.6 85847.6 86840.6 11053.0 11188.2 11280.9 11512.0 11774.3 14222.3 14559.7 16338.6 16340.5 16373.0 7872.9 7949.6 8092.0 8167.0 8193.7 7362.4 7403.6 7410.8 7537.3 7575.0 4833.8 4847.9 4851.6 4862.6 4895.9 1965.6 2005.4 2018.6 2032.0 2043.5 932.3 985.6 1000.4 1001.0 1031.8 18450.8 18999.2 19379.0 19404.3 19452.1 981.3 1026.6 1054.8 1055.1 1070.0 10379.3 10739.6 10806.2 10924.4 10928.2 481108.0 486367.0 496318.0 496715.0 505265.0 90718.5 91285.0 91558.5 92712.5 95057.0 1055.5 1080.1 1105.4 1116.2 1117.4 17158.2 17373.9 17388.8 17550.7 17634.3 26593.9 36962.0 38533.4 38602.8 39374.0 55473.0 57861.4 58664.4 59140.4 59296.1 8710.7 8891.5 8911.4 8970.0 9156.5 10073.5 13645.5 13959.8 14135.3 14178.1 12147.5 12224.4 12323.9 12407.9 12417.8 11732.0 11852.3 11861.3 12018.5 12196.8 6485.6 11321.3 12043.3 12051.3 12257.2 8967.0 9064.9 9098.4 9207.8 9271.6 11406.8 11677.6 11679.6 11737.5 12057.4 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | [#3] mootools.html Cycles:180 Average:6065.95 Median:2639.45 stddev:3502.18 (132.7%) stddev-sans-first:3494.60 16:31:09 INFO - PROCESS | 9952 | Values: 1419.7 1478.0 1550.1 1632.2 1749.6 8600.0 8916.8 8930.5 9164.7 9176.8 8883.3 9063.6 9072.5 9093.5 9108.4 2445.9 2471.6 2473.0 2474.2 2474.5 2453.1 2514.0 2523.4 2524.7 2525.0 2442.4 2443.2 2481.6 2488.7 2491.2 2358.1 2358.8 2375.6 2430.6 2431.7 2291.2 2345.9 2347.8 2352.6 2354.3 7536.1 8658.1 8968.6 9070.4 9078.9 5464.6 5686.5 5686.7 5689.5 5690.6 3395.8 3492.9 3530.2 3537.6 3546.3 1116.7 1126.5 1133.2 1178.8 1180.1 13183.5 13240.1 13354.5 13367.1 13377.5 4771.6 4799.5 4803.7 4815.8 4907.6 10248.2 10256.1 10265.5 10269.0 10277.7 5562.3 5602.8 5620.0 5624.7 5626.6 5129.6 5245.8 5257.0 5271.9 5327.9 3805.7 3823.7 3884.7 3934.7 3950.7 1150.7 1153.2 1160.5 1168.4 1190.8 961.2 972.4 1010.6 1025.4 1043.2 6113.6 6205.4 6249.5 6258.5 6314.7 472.1 479.4 493.8 494.1 496.5 4276.3 4326.5 4357.2 4357.2 4364.2 11272.9 11689.8 11696.7 11699.6 11719.9 11528.3 11610.6 11628.9 11633.8 11648.5 8591.2 8758.2 8759.5 8764.6 8769.2 6128.8 6163.5 6168.0 6291.1 6350.9 10089.5 10206.1 10431.4 10465.7 10858.3 10818.8 10910.1 11076.6 11097.2 11107.4 8158.8 8269.9 8310.5 8338.7 8354.0 8773.1 8810.8 8888.6 8922.2 8924.9 8180.8 8224.3 8247.8 8318.1 8480.2 8096.8 8121.9 8139.9 8243.5 8296.5 7100.1 7248.9 7301.9 7314.8 7324.2 4956.2 5325.9 5432.8 5487.1 5524.7 5407.0 5473.5 5492.4 5601.1 5662.7 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | [#4] prototype.html Cycles:180 Average:4658.17 Median:5343.40 stddev:1356.04 (25.4%) stddev-sans-first:1320.42 16:31:09 INFO - PROCESS | 9952 | Values: 333.7 341.0 349.9 350.9 353.7 5224.5 5317.6 5330.9 5344.8 5361.9 5173.7 5195.6 5223.2 5261.7 5262.8 4003.4 4163.6 4164.0 4167.4 4172.9 4037.4 4172.7 4186.5 4187.5 4197.9 4103.6 4121.5 4129.9 4139.9 4165.8 3995.2 4063.7 4127.5 4133.6 4187.8 3602.0 3617.9 3690.2 3694.6 3701.7 5094.6 5121.1 5133.4 5142.3 5152.7 4603.8 4769.2 4770.2 4774.3 4786.3 5480.3 5488.7 5496.3 5507.4 5520.2 4042.2 4042.5 4050.3 4056.6 4074.0 5530.5 5742.6 5775.8 5798.8 5825.2 4104.0 4108.5 4109.9 4122.3 4126.4 4177.3 4179.3 4183.1 4187.1 4198.6 4725.9 4751.5 4761.1 4770.0 4805.1 5230.9 5557.5 5569.3 5571.6 5571.6 1409.6 1428.6 1435.3 1438.9 1445.9 903.5 914.5 917.1 920.9 934.6 4783.3 5228.3 5239.2 5342.0 5354.4 5622.9 5630.3 5641.7 5666.5 5676.8 5191.0 5330.4 5347.0 5393.6 5406.0 2974.2 2985.8 3001.3 3005.0 3005.9 5746.8 5847.7 5861.4 5871.3 5881.3 5522.9 5617.4 5670.5 5721.7 5765.5 5747.5 5789.4 5797.5 5816.7 5843.2 5435.2 5581.7 5643.5 5646.7 5681.4 5581.7 5591.4 5600.7 5603.3 5604.6 5741.6 5762.2 5768.8 5810.4 5825.6 3894.4 3972.8 3990.9 4017.1 4117.5 5604.3 5643.5 5646.3 5652.2 5677.4 5520.3 5532.7 5596.7 5597.5 5610.5 5388.3 5508.2 5557.6 5567.9 5601.1 5421.6 5430.8 5465.9 5469.6 5479.5 5385.8 5485.2 5496.8 5498.8 5622.5 5310.3 5443.6 5445.0 5449.9 5469.9 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - PROCESS | 9952 | [#5] yui.html Cycles:180 Average:16151.38 Median:4737.69 stddev:19118.21 (403.5%) stddev-sans-first:19137.17 16:31:09 INFO - PROCESS | 9952 | Values: 819.3 819.6 845.8 849.2 850.5 15029.3 15896.8 15986.6 16021.1 16037.4 14548.9 14585.1 14611.2 14616.0 14621.2 694.3 823.0 825.3 848.4 852.2 820.0 829.2 832.9 833.1 837.4 866.7 868.8 869.4 872.8 873.5 729.5 872.3 898.1 898.5 901.6 652.2 655.1 657.5 657.5 657.5 13866.4 13933.2 13960.7 13986.4 14016.1 13072.2 13092.1 14125.6 14279.4 14369.3 11174.2 11346.9 11673.6 11739.6 11777.9 4444.3 4490.6 4517.5 4664.9 4673.7 57464.1 58346.1 58353.7 58695.1 58957.0 11258.9 11384.0 11409.6 11440.7 11491.9 19896.2 20168.9 20448.6 20454.2 20514.1 8345.6 8622.1 8717.4 8761.6 8926.7 4714.8 4760.6 4800.3 4895.3 4921.9 3578.6 3650.9 3686.8 3690.8 3700.9 622.4 660.7 672.5 745.1 751.6 662.0 679.2 763.6 771.9 772.7 9159.5 9627.1 9731.2 9791.8 9913.6 640.3 720.6 751.9 771.0 786.6 4512.3 4566.6 4570.6 4579.5 4614.3 66750.0 69181.3 69341.0 75149.0 76325.2 59251.4 60053.1 61034.7 61891.7 62729.7 55183.7 55766.4 56356.7 56748.1 56817.4 9254.9 9464.3 9467.5 9479.4 9532.2 34662.8 35114.8 36039.0 37361.4 38084.2 50790.5 50828.7 51595.5 51825.0 52911.7 13016.7 13128.0 13137.7 13353.4 13769.2 14234.3 14296.6 14512.6 14695.7 15843.8 10636.6 13438.8 13786.4 14184.4 14360.2 13229.5 13752.0 13793.5 14212.8 14502.2 13787.2 13907.6 14042.2 14559.8 14662.0 11400.3 11505.4 11660.0 11736.6 12009.8 11559.7 11801.9 12018.5 12111.8 12244.4 16:31:09 INFO - PROCESS | 9952 | -------- Summary: end -------- 16:31:09 INFO - PROCESS | 9952 | 16:31:09 INFO - TEST-INFO | 9952: exit 0 16:31:09 INFO - TEST-OK | dromaeo_css | took 1112423ms 16:31:09 INFO - TEST-START | kraken 16:31:09 INFO - operating with platform_type : linux_ 16:31:09 INFO - Initialising browser for kraken test... 16:31:09 INFO - TEST-INFO | started process 10133 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnDAATb/profile http://localhost:43397/getInfo.html) 16:31:13 INFO - PROCESS | 10133 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 16:31:13 INFO - PROCESS | 10133 | __metrics Screen width/height:1600/1200 16:31:13 INFO - PROCESS | 10133 | colorDepth:24 16:31:13 INFO - PROCESS | 10133 | Browser inner width/height: 1024/697 16:31:13 INFO - PROCESS | 10133 | __metrics 16:31:14 ERROR - PROCESS | 10133 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:31:15 INFO - TEST-INFO | 10133: exit 0 16:31:15 INFO - Browser initialized. 16:31:15 INFO - Running cycle 1/1 for kraken test... 16:31:15 INFO - TEST-INFO | started process 10214 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnDAATb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:27 INFO - PROCESS | 10214 | RSS: Main: 152961024 16:31:27 INFO - PROCESS | 10214 | 16:31:29 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43397/tests/kraken/driver.html?testName=audio-beat-detection) 16:31:30 INFO - PROCESS | 10214 | RSS: Main: 209354752 16:31:30 INFO - PROCESS | 10214 | 16:31:33 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43397/tests/kraken/driver.html?testName=audio-dft) 16:31:33 INFO - PROCESS | 10214 | RSS: Main: 198152192 16:31:33 INFO - PROCESS | 10214 | 16:31:37 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43397/tests/kraken/driver.html?testName=audio-fft) 16:31:37 INFO - PROCESS | 10214 | RSS: Main: 265252864 16:31:37 INFO - PROCESS | 10214 | 16:31:39 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43397/tests/kraken/driver.html?testName=audio-oscillator) 16:31:40 INFO - PROCESS | 10214 | RSS: Main: 174346240 16:31:40 INFO - PROCESS | 10214 | 16:31:42 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43397/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:43 INFO - PROCESS | 10214 | RSS: Main: 178794496 16:31:43 INFO - PROCESS | 10214 | 16:31:46 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43397/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:47 INFO - PROCESS | 10214 | RSS: Main: 214654976 16:31:47 INFO - PROCESS | 10214 | 16:31:50 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43397/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:51 INFO - PROCESS | 10214 | RSS: Main: 214597632 16:31:51 INFO - PROCESS | 10214 | 16:31:54 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43397/tests/kraken/driver.html?testName=json-parse-financial) 16:31:54 INFO - PROCESS | 10214 | RSS: Main: 215666688 16:31:54 INFO - PROCESS | 10214 | 16:31:56 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43397/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:57 INFO - PROCESS | 10214 | RSS: Main: 204910592 16:31:57 INFO - PROCESS | 10214 | 16:31:58 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:31:59 INFO - PROCESS | 10214 | RSS: Main: 208510976 16:31:59 INFO - PROCESS | 10214 | 16:32:01 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:02 INFO - PROCESS | 10214 | RSS: Main: 218238976 16:32:02 INFO - PROCESS | 10214 | 16:32:05 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:05 INFO - PROCESS | 10214 | RSS: Main: 224030720 16:32:05 INFO - PROCESS | 10214 | 16:32:08 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:32:09 INFO - PROCESS | 10214 | RSS: Main: 221536256 16:32:09 INFO - PROCESS | 10214 | 16:32:10 INFO - PROCESS | 10214 | Cycle 1(1): loaded http://localhost:43397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43397/tests/kraken/driver.html?testName=ai-astar) 16:32:10 INFO - PROCESS | 10214 | RSS: Main: 267108352 16:32:10 INFO - PROCESS | 10214 | 16:32:10 INFO - PROCESS | 10214 | __start_tp_report 16:32:10 INFO - PROCESS | 10214 | _x_x_mozilla_page_load 16:32:10 INFO - PROCESS | 10214 | _x_x_mozilla_page_load_details 16:32:10 INFO - PROCESS | 10214 | |i|pagename|runs| 16:32:10 INFO - PROCESS | 10214 | |0;ai-astar;109;104;109;107;109;111;109;109;109;107 16:32:10 INFO - PROCESS | 10214 | |1;audio-beat-detection;157;125;155;203;126;148;154;125;178;123 16:32:10 INFO - PROCESS | 10214 | |2;audio-dft;160;162;161;160;161;160;163;161;161;161 16:32:10 INFO - PROCESS | 10214 | |3;audio-fft;76;75;74;158;74;74;76;74;130;75 16:32:10 INFO - PROCESS | 10214 | |4;audio-oscillator;91;90;148;92;92;91;119;91;93;120 16:32:10 INFO - PROCESS | 10214 | |5;imaging-gaussian-blur;116;117;116;116;117;117;117;116;116;117 16:32:10 INFO - PROCESS | 10214 | |6;imaging-darkroom;145;145;145;145;144;145;145;146;145;145 16:32:10 INFO - PROCESS | 10214 | |7;imaging-desaturate;95;95;94;95;95;94;94;95;94;94 16:32:10 INFO - PROCESS | 10214 | |8;json-parse-financial;55;58;54;54;54;59;56;108;53;52 16:32:10 INFO - PROCESS | 10214 | |9;json-stringify-tinderbox;58;58;57;58;59;59;57;59;57;58 16:32:10 INFO - PROCESS | 10214 | |10;stanford-crypto-aes;85;77;78;77;77;79;79;90;79;80 16:32:10 INFO - PROCESS | 10214 | |11;stanford-crypto-ccm;125;127;117;117;121;119;118;117;190;136 16:32:10 INFO - PROCESS | 10214 | |12;stanford-crypto-pbkdf2;168;159;162;163;158;161;163;158;163;158 16:32:10 INFO - PROCESS | 10214 | |13;stanford-crypto-sha256-iterative;67;63;63;64;62;62;62;62;61;62 16:32:10 INFO - PROCESS | 10214 | __end_tp_report 16:32:10 INFO - PROCESS | 10214 | __start_cc_report 16:32:10 INFO - PROCESS | 10214 | _x_x_mozilla_cycle_collect,1859 16:32:10 INFO - PROCESS | 10214 | __end_cc_report 16:32:10 INFO - PROCESS | 10214 | __startTimestamp1463268730987__endTimestamp 16:32:10 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | ------- Summary: start ------- 16:32:11 INFO - PROCESS | 10214 | Number of tests: 14 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#0] ai-astar Cycles:10 Average:108.30 Median:109.00 stddev:1.89 (1.7%) stddev-sans-first:1.99 16:32:11 INFO - PROCESS | 10214 | Values: 109.0 104.0 109.0 107.0 109.0 111.0 109.0 109.0 109.0 107.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:154.50 stddev:26.33 (17.0%) stddev-sans-first:27.78 16:32:11 INFO - PROCESS | 10214 | Values: 157.0 125.0 155.0 203.0 126.0 148.0 154.0 125.0 178.0 123.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 16:32:11 INFO - PROCESS | 10214 | Values: 160.0 162.0 161.0 160.0 161.0 160.0 163.0 161.0 161.0 161.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#3] audio-fft Cycles:10 Average:88.60 Median:74.50 stddev:29.95 (40.2%) stddev-sans-first:31.41 16:32:11 INFO - PROCESS | 10214 | Values: 76.0 75.0 74.0 158.0 74.0 74.0 76.0 74.0 130.0 75.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:91.00 stddev:19.75 (21.7%) stddev-sans-first:20.49 16:32:11 INFO - PROCESS | 10214 | Values: 91.0 90.0 148.0 92.0 92.0 91.0 119.0 91.0 93.0 120.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:32:11 INFO - PROCESS | 10214 | Values: 116.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 16:32:11 INFO - PROCESS | 10214 | Values: 145.0 145.0 145.0 145.0 144.0 145.0 145.0 146.0 145.0 145.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 16:32:11 INFO - PROCESS | 10214 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:54.50 stddev:16.90 (31.0%) stddev-sans-first:17.81 16:32:11 INFO - PROCESS | 10214 | Values: 55.0 58.0 54.0 54.0 54.0 59.0 56.0 108.0 53.0 52.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:58.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 16:32:11 INFO - PROCESS | 10214 | Values: 58.0 58.0 57.0 58.0 59.0 59.0 57.0 59.0 57.0 58.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:79.00 stddev:4.20 (5.3%) stddev-sans-first:4.07 16:32:11 INFO - PROCESS | 10214 | Values: 85.0 77.0 78.0 77.0 77.0 79.0 79.0 90.0 79.0 80.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:123.00 stddev:22.38 (18.2%) stddev-sans-first:23.69 16:32:11 INFO - PROCESS | 10214 | Values: 125.0 127.0 117.0 117.0 121.0 119.0 118.0 117.0 190.0 136.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:162.50 stddev:3.20 (2.0%) stddev-sans-first:2.30 16:32:11 INFO - PROCESS | 10214 | Values: 168.0 159.0 162.0 163.0 158.0 161.0 163.0 158.0 163.0 158.0 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - PROCESS | 10214 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:62.50 stddev:1.69 (2.7%) stddev-sans-first:0.87 16:32:11 INFO - PROCESS | 10214 | Values: 67.0 63.0 63.0 64.0 62.0 62.0 62.0 62.0 61.0 62.0 16:32:11 INFO - PROCESS | 10214 | -------- Summary: end -------- 16:32:11 INFO - PROCESS | 10214 | 16:32:11 INFO - TEST-INFO | 10214: exit 0 16:32:12 INFO - TEST-OK | kraken | took 62098ms 16:32:12 INFO - SUITE-END | took 1174s 16:32:12 INFO - Completed test suite (00:19:34) 16:32:12 INFO - javascript benchmark 16:32:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3118.2048577612704, 3186.856591453384, 3210.438173319669, 3219.919502012448, 3236.821974791387, 5239.947600523994, 5301.0, 5399.2441058251825, 5501.119820828668, 5530.032244357231, 5425.403205647375, 5427.158790387486, 5427.185922111686, 5449.891002179954, 5509.504144626982, 4854.781534830932, 4910.2143657014885, 4972.0, 4977.178765503706, 5122.769475373599, 4970.527799859007, 4993.725345106018, 5015.648904576681, 5048.242763585455, 5048.368953880765, 4831.033793241349, 4877.804887804501, 4890.266460031024, 4898.706077635317, 4904.043711476245, 4883.951160488421, 4895.388076490439, 4967.180415231483, 4982.427020892607, 5010.273510341017, 4731.597814185799, 4933.876653083666, 4934.309196712463, 4968.850934471971, 4971.428285747149, 5337.092694241949, 5382.165764306539, 5397.4062853086125, 5466.070767969415, 5490.450954904518, 5880.26496687914, 5889.204957330791, 5901.970490147564, 5902.2327097477055, 5931.970340148272, 5667.178259152441, 5715.228444160027, 5722.799702010409, 5916.822495325147, 5933.139694744238, 5400.675959442407, 5421.783128674848, 5425.565954723613, 5436.293281873332, 5442.510174084351, 5910.8817823643285, 5947.643141411442, 5954.732037058343, 5957.76168953237, 5957.791477298274, 5551.278333816578, 5600.411956744565, 5605.411431799684, 5694.715264236772, 5717.199592057115, 5745.482906538432, 5808.332041815161, 5820.970895145497, 5837.678927658934, 5844.1233814927255, 5818.4763371296785, 5819.563532734977, 5820.35976042635, 5824.097264923944, 5840.678762668094, 5528.585356098309, 5680.403557626472, 5768.221290125779, 5826.650400975955, 5840.707964601753, 4348.652107831366, 4374.868753937387, 4390.3414487827085, 4396.12077584478, 4428.535003824616, 3898.0449789801114, 3905.2384784967226, 3913.002184442949, 3914.4911161548816, 3955.4857868476915, 5343.450399384135, 5411.0, 5452.781888724407, 5497.752601132958, 5509.862253443615, 5839.883202335929, 5848.766049358063, 5884.676342801102, 5901.17383566301, 5942.64344139353, 5518.2826232590305, 5549.833504994856, 5553.888922221533, 5563.554915606742, 5567.860803479945, 4855.6601037927, 4938.037082768898, 4951.777170027285, 5016.222485514678, 5077.923831142536, 5953.642781433128, 5981.102834574762, 5988.191594134823, 5989.460948514655, 6053.152558641794, 5780.884382312414, 5807.738651760766, 5808.186853840381, 5808.593398462236, 5809.099589563624, 5748.223989761496, 5900.321463031806, 5982.790602328898, 6017.518598512021, 6043.8186854394435, 5719.428057194247, 5781.537477001913, 5786.42135786418, 5794.507466865195, 5794.507466865363, 5894.528437724886, 5894.616849904743, 5903.203067585992, 5908.822735317947, 5908.822735317947, 5504.0643090675485, 5607.747651355618, 5613.607047506799, 5655.632383895034, 5697.971510142423, 5665.801696940587, 5672.2058911751565, 5677.943220567741, 5679.77280908759, 5681.4034526374135, 5414.75633596497, 5584.804531841366, 5602.355729091207, 5605.971970140286, 5606.915896261641, 5621.128725047624, 5661.688607126647, 5698.6010979232715, 5780.3352614448195, 5792.0, 5610.18652295423, 5614.438556144353, 5617.157426386075, 5624.606277560531, 5669.574781891292], "value": 5352.193026528159, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.1946147853704, 647.5111290975312, 649.5777744466096, 665.873484038033, 665.8967859981705, 41348.37977430341, 43183.13633727324, 43290.0, 47228.0, 47621.28568071482, 44898.32652510215, 45804.312935306, 45819.31271030937, 45869.770651146784, 45926.0, 8669.393142480014, 8886.955565222197, 8889.199972002518, 8901.821963560726, 8901.999999999967, 8768.430052046597, 8880.8667869982, 8880.8667869982, 8883.466991980502, 8887.15572020657, 8828.690995815148, 8930.687425940094, 8936.821263574724, 8949.552522373888, 8958.910410895905, 8801.031886492481, 8809.471431714086, 8846.911530884674, 8910.331725120643, 8913.732588022338, 8781.473111613324, 8878.28973682104, 8887.688930887452, 8916.108389161096, 8918.821623567557, 41363.793181034205, 41575.79212103951, 41876.79061604703, 41889.58110418918, 41911.0, 3797.8290976906105, 3807.2195199983935, 4037.899052523681, 4054.493188351456, 4389.714668546534, 32096.679033209602, 32505.0, 32628.184295392566, 32655.6734432655, 33736.1565960853, 4337.544557821415, 4388.978055109704, 4403.845865394728, 4470.642348612103, 4542.704724192917, 353808.0, 354382.22808886046, 355691.0, 356430.0, 356637.0, 54271.72864135733, 54585.181222281695, 54635.726821365635, 54790.0, 54924.0, 60212.69893650504, 60570.0, 60822.0, 61368.0, 61454.69272653608, 16443.506694799176, 16638.25127869249, 16743.832561674226, 16757.664846703243, 16802.243899024572, 12769.106162568716, 12808.6797830055, 12819.230846149261, 12828.55100071493, 12831.486740530274, 14621.853781462261, 15024.323905424282, 15038.097714137186, 15253.542393728207, 15358.769618455732, 5494.36814766299, 6113.938860611337, 6194.845128871724, 6247.75008999644, 6634.369734875179, 1064.5794911010107, 1119.4682525800342, 1172.0, 1191.3745283726114, 1239.7148655809035, 30216.84891575528, 30269.69730302713, 30376.240593984887, 30674.38651226963, 30884.76460941537, 1159.263867444179, 1168.2406435816617, 1170.5259369955181, 1179.257068047125, 1194.0030074887566, 16572.917135414245, 16587.585310367336, 16699.244624636798, 16810.57973550671, 16888.0, 531145.0, 534189.0, 536743.0, 537358.0, 538248.0, 58232.708836455546, 58371.41628583745, 58599.4140058594, 58642.41357586455, 58716.70641646764, 1340.4102195034154, 1393.7351903138372, 1474.6313421644588, 1475.284487023814, 1488.0699562773266, 32368.190795230308, 32621.18447038843, 33671.663283366855, 33787.457828934705, 34129.31741365109, 49660.503394965584, 49814.750926245135, 49893.0, 49945.0, 50689.74655126701, 427285.0, 427703.0, 427962.0, 428680.0, 429363.0, 30654.693453066076, 33274.500882487264, 35826.64173358233, 37539.0, 39613.207735845695, 41428.79285603553, 45709.542904571856, 46665.06669866652, 46893.29660055033, 47813.5218647823, 39305.803470982464, 39308.213835722556, 39729.2054158921, 39823.40264895971, 40585.391219132325, 38179.42730858984, 38207.426888596136, 38532.42201366926, 38735.80632096934, 39058.21883562256, 40126.39810402788, 40295.194096117324, 41246.793766030976, 41602.37596436116, 42190.57809421867, 34924.12689682778, 36418.65574752864, 36554.81722591476, 36827.79033257906, 37234.0, 27982.3004424891, 28317.71682283151, 28350.858245708638, 28682.0, 28857.0], "value": 20194.411596779886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.65098585779873, 388.4173739390912, 394.34735512726445, 395.3042644944897, 417.78484080698445, 14110.929445352758, 17529.91235043823, 17615.295388184473, 19169.233230670772, 19237.615247695037, 17265.136743162853, 18291.268349266047, 18810.717839232424, 19290.51773705658, 19327.51681207967, 5492.395836457987, 5513.8345849624575, 5609.719514024282, 5672.5178359839465, 5684.118961560965, 4010.358342665174, 5610.326760788711, 5618.971905140468, 5638.182463542784, 5917.378675239102, 6171.13604095427, 6284.528660350469, 6304.495640348761, 6312.968435157818, 6315.147455093573, 6024.518038556905, 6251.781187658433, 6288.213973253343, 6288.465480434145, 6292.811215663537, 5543.750531226105, 5549.5837812164245, 5551.861203469905, 5552.611317207797, 5574.637648552831, 21198.5760284795, 22367.664485032576, 23188.608683479044, 23191.101112360953, 23326.300210993526, 2579.200447861169, 2640.4719056188646, 2766.4250270974485, 2797.38457539341, 3119.142235885127, 14301.213433261166, 14607.0, 14608.612181842707, 14850.108993460426, 15011.924940375338, 1972.8125828046313, 1986.1757370691146, 1995.2717258200798, 2048.4162013826135, 2138.700581918533, 82249.0, 84677.15322846755, 85270.57364713137, 85847.57076214641, 86840.56579717124, 11053.0, 11188.160887933438, 11280.887191128148, 11512.0, 11774.293542387484, 14222.288885555681, 14559.708805823824, 16338.591535211715, 16340.509784706477, 16373.035235771784, 7872.881906771403, 7949.562774047483, 8092.0, 8166.959165204136, 8193.713220037389, 7362.3741981932135, 7403.629818509053, 7410.777676669709, 7537.283958023979, 7574.962125189339, 4833.806647734052, 4847.854564363075, 4851.636127290467, 4862.610991120702, 4895.853124406202, 1965.5774008588223, 2005.3883565512658, 2018.576099019193, 2031.9695204571942, 2043.468698138465, 932.3007744191856, 985.6008316631776, 1000.3997601439224, 1001.0490034467285, 1031.7626945802508, 18450.815491845176, 18999.24003039863, 19379.0, 19404.32084877051, 19452.124654390587, 981.3277904635347, 1026.645807196516, 1054.8101341758404, 1055.134789472625, 1069.983515660126, 10379.325343852626, 10739.624113156002, 10806.243562950518, 10924.399158046384, 10928.180386471047, 481108.0, 486367.0, 496318.0, 496715.0, 505265.0, 90718.54640726886, 91285.0, 91558.54220728854, 92712.53643731738, 95057.0, 1055.5461151704687, 1080.0711388206137, 1105.4141305108305, 1116.1517246892258, 1117.402189828454, 17158.22787974569, 17373.913130434266, 17388.82611173872, 17550.73673894918, 17634.294628214728, 26593.867030665497, 36962.0, 38533.42199866948, 38602.80698596489, 39374.0, 55473.0, 57861.4213857856, 58664.413355867604, 59140.40859591349, 59296.11055834252, 8710.738677839676, 8891.510966896623, 8911.420757650732, 8969.955150224207, 9156.542172891463, 10073.546690398804, 13645.522406716114, 13959.790603141164, 14135.293235338399, 14178.14931104137, 12147.453364598437, 12224.44989975471, 12323.9383803084, 12407.875921240671, 12417.813732794197, 11732.0, 11852.288862668267, 11861.347625880659, 12018.459169337226, 12196.75606487883, 6485.578437401554, 11321.320720756781, 12043.313936049135, 12051.276923384625, 12257.203281786655, 8966.955165224132, 9064.864027039732, 9098.408603440756, 9207.815843683224, 9271.6291348348, 11406.82889756671, 11677.598688157475, 11679.591214307458, 11737.530498779954, 12057.397130143283], "value": 9399.73305791313, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.666378401075, 1477.9926100369496, 1550.1164336328293, 1632.183908045977, 1749.6063385738203, 8600.010998735148, 8916.777080572982, 8930.464172149668, 9164.679236226717, 9176.816463670739, 8883.289336853053, 9063.592138353773, 9072.546372681356, 9093.545322733853, 9108.362414630978, 2445.877706114696, 2471.5921872890954, 2472.9752702473015, 2474.208253358926, 2474.4556197636493, 2453.141400509825, 2513.962290565643, 2523.3817714659936, 2524.684414448194, 2524.9747502525015, 2442.438239204984, 2443.1571107967725, 2481.627755836621, 2488.664030355897, 2491.190363131989, 2358.0921345282018, 2358.8466749661216, 2375.5961486547244, 2430.6232533957264, 2431.6960379952507, 2291.163725240292, 2345.9296221113395, 2347.7534858839745, 2352.5765362234793, 2354.281944007077, 7536.095668519758, 8658.090900455487, 8968.641254349819, 9070.410423322463, 9078.86381704275, 5464.644798088151, 5686.459786320293, 5686.744096515667, 5689.459501347406, 5690.573207009491, 3395.7622966392364, 3492.912677183065, 3530.1527633368064, 3537.557805274341, 3546.343926373603, 1116.743149075709, 1126.5212284779018, 1133.1501373969522, 1178.7524619829846, 1180.1090176916373, 13183.538576149884, 13240.139390939557, 13354.465821367134, 13367.131136476099, 13377.464901403931, 4771.6400825764495, 4799.520047995173, 4803.663743537919, 4815.759212039454, 4907.631927605373, 10248.180145588334, 10256.07695307426, 10265.538050787733, 10268.97310268982, 10277.691669249934, 5562.276904002454, 5602.831915042555, 5620.0, 5624.690642014729, 5626.578006649518, 5129.589632829365, 5245.763940622681, 5257.0, 5271.947280527223, 5327.86680332987, 3805.7060599396336, 3823.713221508398, 3884.708022266876, 3934.724569280123, 3950.7234493585747, 1150.71232191952, 1153.1985270237271, 1160.4835848047708, 1168.4449886304108, 1190.8451901252781, 961.2118063188119, 972.4019727867452, 1010.56545685356, 1025.3847691385108, 1043.175891046065, 6113.57204995655, 6205.410486003822, 6249.500039996789, 6258.530610204253, 6314.747410103544, 472.1430603454719, 479.3815977389129, 493.77286448234213, 494.1327969413656, 496.5183771741477, 4276.294411422081, 4326.459192600925, 4357.150355680612, 4357.172137293904, 4364.192624364503, 11272.943635281772, 11689.824652630217, 11696.70758231051, 11699.649010529698, 11719.882801172049, 11528.250663706833, 11610.593629223105, 11628.88371116278, 11633.825492617618, 11648.53405863773, 8591.226789588842, 8758.167974042455, 8759.518226497476, 8764.605592748341, 8769.166929141722, 6128.754849805958, 6163.506919446344, 6168.0, 6291.087792270187, 6350.936490635219, 10089.545970431202, 10206.132478739093, 10431.426271555138, 10465.738356541147, 10858.34849909008, 10818.837717434088, 10910.127189824954, 11076.5569377224, 11097.223194376316, 11107.38909359993, 8158.836823263383, 8269.917300826915, 8310.501369917823, 8338.666453341799, 8354.0, 8773.078826723102, 8810.823783524165, 8888.64445422176, 8922.241609463008, 8924.86612700823, 8180.7954801130445, 8224.259816616533, 8247.752567422987, 8318.085010648703, 8480.236778689947, 8096.757097287091, 8121.918780812352, 8139.918600813916, 8243.505389676639, 8296.543690097102, 7100.147982242164, 7248.855022899407, 7301.853962920818, 7314.780556583311, 7324.157721862054, 4956.182229932021, 5325.946740532545, 5432.7555260012605, 5487.122060470306, 5524.723763811874, 5407.0, 5473.480019398151, 5492.368377636463, 5601.131824567198, 5662.716864156858], "value": 4744.854328587009, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.68966860819427, 341.02636971446515, 349.88803582853495, 350.8964855367665, 353.73116431512045, 5224.48489937918, 5317.59083842782, 5330.92003619946, 5344.808628462326, 5361.892762144755, 5173.689578625285, 5195.58435325174, 5223.190405487156, 5261.657992230512, 5262.81580144695, 4003.4195041718935, 4163.646090082347, 4163.979180104103, 4167.437395951553, 4172.853950111747, 4037.3540233562635, 4172.685604034735, 4186.492862569473, 4187.476565429321, 4197.895052623693, 4103.610157035092, 4121.526024507175, 4129.876103716878, 4139.882231797413, 4165.791710414483, 3995.180987897474, 4063.6952228582677, 4127.463429754142, 4133.627973482401, 4187.790610469464, 3602.0, 3617.9457308140136, 3690.15126520899, 3694.556653201606, 3701.7038636908983, 5094.617903657204, 5121.129408000612, 5133.435322114565, 5142.305788718512, 5152.742362881879, 4603.838865639686, 4769.236922092483, 4770.189067858472, 4774.307725379836, 4786.305985632064, 5480.287562616834, 5488.69812160331, 5496.285482887239, 5507.421720719347, 5520.227168196456, 4042.241624893181, 4042.535108462506, 4050.25070361984, 4056.634902858757, 4074.022234663659, 5530.5299049580835, 5742.588112992553, 5775.797847075374, 5798.768049278066, 5825.242718446575, 4103.958960410418, 4108.5275193352545, 4109.938350924738, 4122.299209134454, 4126.442930204444, 4177.310743727312, 4179.331306990927, 4183.142455796555, 4187.14163596462, 4198.580141985777, 4725.929111063337, 4751.501092385319, 4761.142994261067, 4770.022145460172, 4805.111054454937, 5230.92153617696, 5557.471695283829, 5569.27599412082, 5571.637843540157, 5571.637843540157, 1409.6194027612487, 1428.649980754701, 1435.2823588205897, 1438.8992770505963, 1445.8771004464738, 903.5405510731263, 914.5198770645464, 917.1012407840353, 920.9271199053162, 934.6168071090821, 4783.282507623884, 5228.346456692913, 5239.161734122522, 5342.0, 5354.4472103089665, 5622.85942851432, 5630.268065151504, 5641.689707066069, 5666.518345940642, 5676.829695109153, 5190.97404512975, 5330.413654498002, 5347.01080300138, 5393.5954803389905, 5405.972970135203, 2974.1523665755158, 2985.8357790321306, 3001.264690150927, 3004.9549256761165, 3005.9398812024074, 5746.82190151018, 5847.736851841677, 5861.3552509223955, 5871.266091738533, 5881.264841894763, 5522.91715624266, 5617.41017193197, 5670.489655930986, 5721.656700597978, 5765.509931655856, 5747.482726554546, 5789.3921138279875, 5797.536197104221, 5816.709164541756, 5843.211166492554, 5435.211894275387, 5581.6930068846605, 5643.463870932255, 5646.689432081275, 5681.403452637579, 5581.7209139543675, 5591.440855914376, 5600.719964001865, 5603.32760068778, 5604.551635869039, 5741.555066959791, 5762.2221000164445, 5768.807853015827, 5810.4189581041555, 5825.563082768894, 3894.3768996961485, 3972.781497017692, 3990.880273591797, 4017.116234428421, 4117.517693630351, 5604.327480702342, 5643.463870932255, 5646.2942132233475, 5652.180433836988, 5677.432256774289, 5520.282363292747, 5532.723363831873, 5596.720163991703, 5597.496225339576, 5610.4670056344585, 5388.315193037357, 5508.173773933942, 5557.5831812613405, 5567.8886422272135, 5601.131824567198, 5421.5933804964, 5430.755615997368, 5465.890682186414, 5469.644473109235, 5479.53423958968, 5385.838424847261, 5485.177223416519, 5496.790706044664, 5498.780048798003, 5622.465865742748, 5310.336207974004, 5443.564514838883, 5445.0, 5449.945500545103, 5469.917951230815], "value": 4228.011287076032, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.3076850061698, 819.5656302159856, 845.754731127973, 849.2017503546666, 850.4854562989395, 15029.32368043438, 15896.84103158968, 15986.600334991632, 16021.118838463879, 16037.35850565976, 14548.854511454882, 14585.051971621837, 14611.19638419889, 14616.049956752804, 14621.195834229113, 694.2502097734434, 822.9876551851727, 825.2531459029587, 848.3594885861166, 852.2074470742207, 820.0118856777599, 829.2288172000068, 832.9083800781909, 833.0669649992018, 837.4221786967004, 866.6879923227633, 868.8349213649418, 869.4348673362333, 872.8254349130168, 873.5370253765515, 729.5002922997737, 872.3021582733819, 898.128815049401, 898.5013317608757, 901.5762591581946, 652.1847690387016, 655.0861548140317, 657.4740207833762, 657.510154934572, 657.5167252069725, 13866.4453421863, 13933.164010159422, 13960.720785584333, 13986.370613322424, 14016.088954217943, 13072.150310229805, 13092.083554151213, 14125.646858828612, 14279.428822847074, 14369.281535923163, 11174.161937854693, 11346.943265283704, 11673.649790506299, 11739.647810565697, 11777.94111029448, 4444.266695995157, 4490.59584637381, 4517.525659805706, 4664.860054198345, 4673.672842900998, 57464.138037929464, 58346.12480812791, 58353.70823145899, 58695.11957320644, 58957.0, 11258.887411125948, 11384.032357249727, 11409.5790857456, 11440.713982150513, 11491.885081149248, 19896.204151833765, 20168.89915550413, 20448.591028179355, 20454.181832726525, 20514.076866541043, 8345.582720863933, 8622.137786221454, 8717.433366831263, 8761.649534018696, 8926.68756593516, 4714.764261786897, 4760.619150467955, 4800.255960326155, 4895.265710143507, 4921.8523444296725, 3578.624244454347, 3650.926981460409, 3686.7972261525338, 3690.7785532868115, 3700.925981480409, 622.411820829312, 660.7092879133166, 672.4821887146869, 745.0798264143779, 751.620431681997, 661.9768308109193, 679.2324673119343, 763.5800309829572, 771.9189485104096, 772.7140957845521, 9159.45043297391, 9627.133557979816, 9731.221502279801, 9791.755206119904, 9913.603455861685, 640.3276559612389, 720.5676594043532, 751.9097708274933, 771.0, 786.6066966516742, 4512.300593408027, 4566.566176213254, 4570.611498022638, 4579.519150489217, 4614.261718125151, 66750.0, 69181.3081869185, 69341.0, 75149.0, 76325.23674763292, 59251.40748592459, 60053.099203511985, 61034.694826525585, 61891.690541547905, 62729.68635156886, 55183.72408137934, 55766.442335576125, 56356.71821640948, 56748.148777768365, 56817.43182568121, 9254.907450925404, 9464.337496375463, 9467.526623668928, 9479.383840050376, 9532.237421006164, 34662.82668586641, 35114.824425877705, 36039.0, 37361.4395784058, 38084.238315232986, 50790.492095078574, 50828.745856270485, 51595.484045159064, 51825.0, 52911.73544132255, 13016.739665206833, 13128.0, 13137.73724525485, 13353.39909704085, 13769.173849569059, 14234.288285585886, 14296.642583935485, 14512.637184070483, 14695.706085878437, 15843.762343564626, 10636.627718029831, 13438.814140022103, 13786.379612917242, 14184.361703723564, 14360.210188439736, 13229.536966206137, 13752.0, 13793.517226897011, 14212.786808197678, 14502.202378869264, 13787.241701706502, 13907.58277251684, 14042.1574705518, 14559.781603276171, 14662.0, 11400.258983166068, 11505.424728763695, 11660.0, 11736.591609006973, 12009.75980480368, 11559.653210403701, 11801.940990294994, 12018.465456860928, 12111.75776484483, 12244.387780611112], "value": 7183.66839365123, "unit": "score"}], "name": "dromaeo_css", "value": 7260.02421098749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 104.0, 109.0, 107.0, 109.0, 111.0, 109.0, 109.0, 109.0, 107.0], "value": 108.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 155.0, 203.0, 126.0, 148.0, 154.0, 125.0, 178.0, 123.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 160.0, 161.0, 160.0, 163.0, 161.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 158.0, 74.0, 74.0, 76.0, 74.0, 130.0, 75.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 148.0, 92.0, 92.0, 91.0, 119.0, 91.0, 93.0, 120.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 144.0, 145.0, 145.0, 146.0, 145.0, 145.0], "value": 145.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 54.0, 54.0, 54.0, 59.0, 56.0, 108.0, 53.0, 52.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 58.0, 59.0, 59.0, 57.0, 59.0, 57.0, 58.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 77.0, 78.0, 77.0, 77.0, 79.0, 79.0, 90.0, 79.0, 80.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 117.0, 117.0, 121.0, 119.0, 118.0, 117.0, 190.0, 136.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 159.0, 162.0, 163.0, 158.0, 161.0, 163.0, 158.0, 163.0, 158.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 64.0, 62.0, 62.0, 62.0, 62.0, 61.0, 62.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1517.1999999999998, "alertThreshold": 2.0}]} 16:32:12 INFO - Return code: 0 16:32:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:32:12 ERROR - # TBPL SUCCESS # 16:32:12 INFO - Running post-action listener: _resource_record_post_action 16:32:12 INFO - ##### 16:32:12 INFO - ##### Finished run-tests step (success) 16:32:12 INFO - ##### 16:32:12 INFO - Running post-run listener: _resource_record_post_run 16:32:12 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 537931776; Read time: 44; Write time: 584620 16:32:12 INFO - TinderboxPrint: CPU usage
13.1% 16:32:12 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 44 16:32:12 INFO - TinderboxPrint: I/O write bytes / time
537,931,776 / 584,620 16:32:12 INFO - TinderboxPrint: CPU idle
8,268.3 (86.9%) 16:32:12 INFO - TinderboxPrint: CPU user
1,200.7 (12.6%) 16:32:12 INFO - TinderboxPrint: Swap in / out
0 / 0 16:32:12 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 246251520; Read time: 0; Write time: 337980 16:32:12 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 291598336; Read time: 44; Write time: 246640 16:32:12 INFO - Running post-run listener: _upload_blobber_files 16:32:12 INFO - Blob upload gear active. 16:32:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:32:12 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 16:32:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:32:13 INFO - (blobuploader) - INFO - Open directory for files ... 16:32:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:32:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:32:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:14 INFO - (blobuploader) - INFO - Done attempting. 16:32:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:32:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:32:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:14 INFO - (blobuploader) - INFO - Done attempting. 16:32:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:32:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:19 INFO - (blobuploader) - INFO - Done attempting. 16:32:19 INFO - (blobuploader) - INFO - Iteration through files over. 16:32:19 INFO - Return code: 0 16:32:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:32:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:32:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d55247e7702f1007c1a05be909fb7f030b6db7e9117e7d1a479462ab35f988b7819cab845e20446e09dd00c3444acebe2f9492e826dbdecd1223730009a5f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9c66a537adeed1ab08233ab4d528cd06036af3c65d27492563535d57c15de4181d9c36710a63b4c867949b4bf69372a4995ce83d3dac2dad7939aa5f776962e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d81f748898b2a670273576fd36b00a2232b09a5b96ac34104ee1ada7927a5979e8b7ca7f463596dfb89142b1138a6d9278e3fb3878c9f2aeb3174abac8741713"} 16:32:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:32:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:32:19 INFO - Contents: 16:32:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d55247e7702f1007c1a05be909fb7f030b6db7e9117e7d1a479462ab35f988b7819cab845e20446e09dd00c3444acebe2f9492e826dbdecd1223730009a5f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9c66a537adeed1ab08233ab4d528cd06036af3c65d27492563535d57c15de4181d9c36710a63b4c867949b4bf69372a4995ce83d3dac2dad7939aa5f776962e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d81f748898b2a670273576fd36b00a2232b09a5b96ac34104ee1ada7927a5979e8b7ca7f463596dfb89142b1138a6d9278e3fb3878c9f2aeb3174abac8741713"} 16:32:19 INFO - Running post-run listener: copy_logs_to_upload_dir 16:32:19 INFO - Copying logs to upload dir... 16:32:19 INFO - mkdir: /builds/slave/test/build/upload/logs 16:32:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.400514 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-05-14 16:32:18.965915) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 16:32:18.969889) ========= 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=62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d55247e7702f1007c1a05be909fb7f030b6db7e9117e7d1a479462ab35f988b7819cab845e20446e09dd00c3444acebe2f9492e826dbdecd1223730009a5f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9c66a537adeed1ab08233ab4d528cd06036af3c65d27492563535d57c15de4181d9c36710a63b4c867949b4bf69372a4995ce83d3dac2dad7939aa5f776962e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d81f748898b2a670273576fd36b00a2232b09a5b96ac34104ee1ada7927a5979e8b7ca7f463596dfb89142b1138a6d9278e3fb3878c9f2aeb3174abac8741713"} build_url:https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/eHKmPmcwTUS6ka-D4vfz5A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d55247e7702f1007c1a05be909fb7f030b6db7e9117e7d1a479462ab35f988b7819cab845e20446e09dd00c3444acebe2f9492e826dbdecd1223730009a5f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9c66a537adeed1ab08233ab4d528cd06036af3c65d27492563535d57c15de4181d9c36710a63b4c867949b4bf69372a4995ce83d3dac2dad7939aa5f776962e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d81f748898b2a670273576fd36b00a2232b09a5b96ac34104ee1ada7927a5979e8b7ca7f463596dfb89142b1138a6d9278e3fb3878c9f2aeb3174abac8741713"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 16:32:18.994626) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 16:32:18.995000) ========= 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=62c568580acbfdaac3eec7be000001dc-1463266473.18220-1230245879 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 16:32:19.080271) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 16:32:19.080621) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 16:32:19.080944) ========= ========= Total master_lag: 0.17 =========