builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1462547322.98 results: success (0) buildid: 20160506052037 builduid: 272a1b5faa8b4aa5992a327d5003c110 revision: 19a1743ceb2e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:42.982529) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:42.982977) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:42.983284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:42.999794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:43.000093) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-06 08:08:43-- 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% 38.0M=0s 2016-05-06 08:08:43 (38.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.271150 ========= master_lag: 0.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:43.570331) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:43.570712) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016787 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:43.601247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:43.601600) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 19a1743ceb2e --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 19a1743ceb2e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-06 08:08:44,323 Setting DEBUG logging. 2016-05-06 08:08:44,323 attempt 1/10 2016-05-06 08:08:44,323 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/19a1743ceb2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 08:08:44,573 unpacking tar archive at: mozilla-central-19a1743ceb2e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.548368 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:44.165439) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:44.165905) ========= script_repo_revision: 19a1743ceb2e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:44.166567) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:44.167111) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 08:08:44.229859) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-05-06 08:08:44.230291) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:08:45 INFO - MultiFileLogger online at 20160506 08:08:45 in /builds/slave/test 08:08:45 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 08:08:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:08:45 INFO - {'append_to_log': False, 08:08:45 INFO - 'base_work_dir': '/builds/slave/test', 08:08:45 INFO - 'blob_upload_branch': 'Firefox', 08:08:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:08:45 INFO - 'branch': 'Firefox', 08:08:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:45 INFO - 'config_files': ('talos/linux_config.py',), 08:08:45 INFO - 'default_actions': ('clobber', 08:08:45 INFO - 'read-buildbot-config', 08:08:45 INFO - 'download-and-extract', 08:08:45 INFO - 'populate-webroot', 08:08:45 INFO - 'create-virtualenv', 08:08:45 INFO - 'install', 08:08:45 INFO - 'run-tests'), 08:08:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:45 INFO - 'download_minidump_stackwalk': True, 08:08:45 INFO - 'download_symbols': 'ondemand', 08:08:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:45 INFO - 'installer_path': 'installer.exe', 08:08:45 INFO - 'log_level': 'info', 08:08:45 INFO - 'log_name': 'talos', 08:08:45 INFO - 'log_to_console': True, 08:08:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:08:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:08:45 INFO - 'opt_config_files': (), 08:08:45 INFO - 'pip_index': False, 08:08:45 INFO - 'sps_profile': False, 08:08:45 INFO - 'sps_profile_interval': 0, 08:08:45 INFO - 'suite': 'dromaeojs', 08:08:45 INFO - 'system_bits': '32', 08:08:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:45 INFO - 'title': 'talos-linux64-ix-042', 08:08:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:45 INFO - 'use_talos_json': True, 08:08:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:08:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:45 INFO - 'work_dir': 'build'} 08:08:45 INFO - ##### 08:08:45 INFO - ##### Running clobber step. 08:08:45 INFO - ##### 08:08:45 INFO - Running pre-action listener: _resource_record_pre_action 08:08:45 INFO - Running main action method: clobber 08:08:45 INFO - rmtree: /builds/slave/test/build 08:08:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:08:45 INFO - Running post-action listener: _resource_record_post_action 08:08:45 INFO - ##### 08:08:45 INFO - ##### Finished clobber step (success) 08:08:45 INFO - ##### 08:08:45 INFO - ##### 08:08:45 INFO - ##### Running read-buildbot-config step. 08:08:45 INFO - ##### 08:08:45 INFO - Running pre-action listener: _resource_record_pre_action 08:08:45 INFO - Running main action method: read_buildbot_config 08:08:45 INFO - Using buildbot properties: 08:08:45 INFO - { 08:08:45 INFO - "project": "", 08:08:45 INFO - "product": "firefox", 08:08:45 INFO - "script_repo_revision": "production", 08:08:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:08:45 INFO - "repository": "", 08:08:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:08:45 INFO - "buildid": "20160506052037", 08:08:45 INFO - "stage_platform": "linux64-pgo", 08:08:45 INFO - "basedir": "/builds/slave/test", 08:08:45 INFO - "nightly_build": "True", 08:08:45 INFO - "buildnumber": 764, 08:08:45 INFO - "slavename": "talos-linux64-ix-042", 08:08:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:08:45 INFO - "platform": "ubuntu64_hw", 08:08:45 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 08:08:45 INFO - "branch": "mozilla-central", 08:08:45 INFO - "repo_path": "mozilla-central", 08:08:45 INFO - "revision": "19a1743ceb2e", 08:08:45 INFO - "pgo_build": "True", 08:08:45 INFO - "builduid": "272a1b5faa8b4aa5992a327d5003c110", 08:08:45 INFO - "slavebuilddir": "test" 08:08:45 INFO - } 08:08:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:08:45 INFO - Running post-action listener: _resource_record_post_action 08:08:45 INFO - ##### 08:08:45 INFO - ##### Finished read-buildbot-config step (success) 08:08:45 INFO - ##### 08:08:45 INFO - ##### 08:08:45 INFO - ##### Running download-and-extract step. 08:08:45 INFO - ##### 08:08:45 INFO - Running pre-action listener: _resource_record_pre_action 08:08:45 INFO - Running main action method: download_and_extract 08:08:45 INFO - mkdir: /builds/slave/test/build/tests 08:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:45 INFO - https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:46 INFO - Downloaded 1361 bytes. 08:08:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:08:46 INFO - Using the following test package requirements: 08:08:46 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:08:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:08:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:08:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'jsshell-linux-x86_64.zip'], 08:08:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:08:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:08:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:08:46 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:08:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:08:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:08:46 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:08:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:08:46 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')]} 08:08:46 INFO - https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:47 INFO - Downloaded 23893813 bytes. 08:08: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'] 08:08: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 08:08:49 INFO - Return code: 0 08:08:49 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 08:08:49 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')]} 08:08:49 INFO - https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:08:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:08:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:08:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:52 INFO - Downloaded 23893813 bytes. 08:08:52 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'] 08:08:52 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 08:08:53 INFO - Return code: 0 08:08: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')]} 08:08:53 INFO - https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:08:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:08:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:08:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:54 INFO - Downloaded 10840152 bytes. 08:08:54 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'] 08:08:54 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 08:08:55 INFO - Return code: 0 08:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:55 INFO - https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:08:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:08:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:08:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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 08:08:57 INFO - Downloaded 58409439 bytes. 08:08:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:08:57 INFO - mkdir: /builds/slave/test/properties 08:08:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:08:57 INFO - Writing to file /builds/slave/test/properties/build_url 08:08:57 INFO - Contents: 08:08:57 INFO - build_url:https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:08:57 INFO - Running post-action listener: _resource_record_post_action 08:08:57 INFO - Running post-action listener: set_extra_try_arguments 08:08:57 INFO - ##### 08:08:57 INFO - ##### Finished download-and-extract step (success) 08:08:57 INFO - ##### 08:08:57 INFO - ##### 08:08:57 INFO - ##### Running populate-webroot step. 08:08:57 INFO - ##### 08:08:57 INFO - Running pre-action listener: _resource_record_pre_action 08:08:57 INFO - Running main action method: populate_webroot 08:08:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:57 INFO - '--tppagecycles', 08:08:57 INFO - '7'], 08:08:57 INFO - 'tests': ['tsvgm']}}, 08:08:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:08:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:08:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:08:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['damp', 'tps']}, 08:08:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'tests': ['damp', 'tps']}, 08:08:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['dromaeo_dom']}, 08:08:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other-e10s': {'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['a11yr', 08:08:57 INFO - 'ts_paint', 08:08:57 INFO - 'tpaint', 08:08:57 INFO - 'sessionrestore', 08:08:57 INFO - 'sessionrestore_no_auto_restore', 08:08:57 INFO - 'tabpaint']}, 08:08:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tsvgx', 08:08:57 INFO - 'tsvgr_opacity', 08:08:57 INFO - 'tart', 08:08:57 INFO - 'tscrollx', 08:08:57 INFO - 'cart']}, 08:08:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:08:57 INFO - 'tsvgr_opacity', 08:08:57 INFO - 'tart', 08:08:57 INFO - 'tscrollx', 08:08:57 INFO - 'cart']}, 08:08:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s'], 08:08:57 INFO - 'tests': ['tp5o']}, 08:08:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'tests': ['tp5o']}, 08:08:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--disable-e10s', 08:08:57 INFO - '--xperf_path', 08:08:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:57 INFO - 'tests': ['tp5n']}, 08:08:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:08:57 INFO - 'talos_options': ['--xperf_path', 08:08:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:57 INFO - 'tests': ['tp5n']}}, 08:08:57 INFO - 'talos.zip': {'path': '', 08:08:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:57 INFO - Running post-action listener: _resource_record_post_action 08:08:57 INFO - ##### 08:08:57 INFO - ##### Finished populate-webroot step (success) 08:08:57 INFO - ##### 08:08:57 INFO - ##### 08:08:57 INFO - ##### Running create-virtualenv step. 08:08:57 INFO - ##### 08:08:57 INFO - Running pre-action listener: _resource_record_pre_action 08:08:57 INFO - Running main action method: create_virtualenv 08:08:57 INFO - Creating virtualenv /builds/slave/test/build/venv 08:08:57 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 08:08:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:08:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:58 INFO - Using real prefix '/usr' 08:08:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:08:58 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:00 INFO - Installing pip.................done. 08:09:00 INFO - Return code: 0 08:09:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09: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', 'pip>=1.5'] in /builds/slave/test/build 08:09: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 pip>=1.5 08:09:00 INFO - Using env: {'DISPLAY': ':0', 08:09:00 INFO - 'HOME': '/home/cltbld', 08:09:00 INFO - 'LANG': 'en_US.UTF-8', 08:09:00 INFO - 'LANGUAGE': 'en_US:en', 08:09:00 INFO - 'LOGNAME': 'cltbld', 08:09:00 INFO - 'MAIL': '/var/mail/cltbld', 08:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:00 INFO - 'MOZ_NO_REMOTE': '1', 08:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:00 INFO - 'NO_EM_RESTART': '1', 08:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:00 INFO - 'PWD': '/builds/slave/test', 08:09:00 INFO - 'SHELL': '/bin/bash', 08:09:00 INFO - 'SHLVL': '1', 08:09:00 INFO - 'TERM': 'linux', 08:09:00 INFO - 'TMOUT': '86400', 08:09:00 INFO - 'USER': 'cltbld', 08:09:00 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:00 INFO - '_': '/tools/buildbot/bin/python'} 08:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:00 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 08:09:00 INFO - Cleaning up... 08:09:00 INFO - Return code: 0 08:09:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09: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', 'psutil>=0.7.1'] in /builds/slave/test/build 08:09: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 psutil>=0.7.1 08:09:00 INFO - Using env: {'DISPLAY': ':0', 08:09:00 INFO - 'HOME': '/home/cltbld', 08:09:00 INFO - 'LANG': 'en_US.UTF-8', 08:09:00 INFO - 'LANGUAGE': 'en_US:en', 08:09:00 INFO - 'LOGNAME': 'cltbld', 08:09:00 INFO - 'MAIL': '/var/mail/cltbld', 08:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:00 INFO - 'MOZ_NO_REMOTE': '1', 08:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:00 INFO - 'NO_EM_RESTART': '1', 08:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:00 INFO - 'PWD': '/builds/slave/test', 08:09:00 INFO - 'SHELL': '/bin/bash', 08:09:00 INFO - 'SHLVL': '1', 08:09:00 INFO - 'TERM': 'linux', 08:09:00 INFO - 'TMOUT': '86400', 08:09:00 INFO - 'USER': 'cltbld', 08:09:00 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:00 INFO - '_': '/tools/buildbot/bin/python'} 08:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:00 INFO - Downloading/unpacking psutil>=0.7.1 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:02 INFO - Installing collected packages: psutil 08:09:02 INFO - Running setup.py install for psutil 08:09:02 INFO - building 'psutil._psutil_linux' extension 08:09:02 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 08:09:03 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 08:09:03 INFO - building 'psutil._psutil_posix' extension 08:09:03 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 08:09:03 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 08:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:03 INFO - Successfully installed psutil 08:09:03 INFO - Cleaning up... 08:09:03 INFO - Return code: 0 08:09:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:09: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 mozsystemmonitor==0.0.0 08:09:03 INFO - Using env: {'DISPLAY': ':0', 08:09:03 INFO - 'HOME': '/home/cltbld', 08:09:03 INFO - 'LANG': 'en_US.UTF-8', 08:09:03 INFO - 'LANGUAGE': 'en_US:en', 08:09:03 INFO - 'LOGNAME': 'cltbld', 08:09:03 INFO - 'MAIL': '/var/mail/cltbld', 08:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:03 INFO - 'MOZ_NO_REMOTE': '1', 08:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:03 INFO - 'NO_EM_RESTART': '1', 08:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:03 INFO - 'PWD': '/builds/slave/test', 08:09:03 INFO - 'SHELL': '/bin/bash', 08:09:03 INFO - 'SHLVL': '1', 08:09:03 INFO - 'TERM': 'linux', 08:09:03 INFO - 'TMOUT': '86400', 08:09:03 INFO - 'USER': 'cltbld', 08:09:03 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:03 INFO - '_': '/tools/buildbot/bin/python'} 08:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:09:05 INFO - Installing collected packages: mozsystemmonitor 08:09:05 INFO - Running setup.py install for mozsystemmonitor 08:09:05 INFO - Successfully installed mozsystemmonitor 08:09:05 INFO - Cleaning up... 08:09:05 INFO - Return code: 0 08:09:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:05 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 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09:05 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 08:09:05 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 08:09:05 INFO - Using env: {'DISPLAY': ':0', 08:09:05 INFO - 'HOME': '/home/cltbld', 08:09:05 INFO - 'LANG': 'en_US.UTF-8', 08:09:05 INFO - 'LANGUAGE': 'en_US:en', 08:09:05 INFO - 'LOGNAME': 'cltbld', 08:09:05 INFO - 'MAIL': '/var/mail/cltbld', 08:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:05 INFO - 'MOZ_NO_REMOTE': '1', 08:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:05 INFO - 'NO_EM_RESTART': '1', 08:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:05 INFO - 'PWD': '/builds/slave/test', 08:09:05 INFO - 'SHELL': '/bin/bash', 08:09:05 INFO - 'SHLVL': '1', 08:09:05 INFO - 'TERM': 'linux', 08:09:05 INFO - 'TMOUT': '86400', 08:09:05 INFO - 'USER': 'cltbld', 08:09:05 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:05 INFO - '_': '/tools/buildbot/bin/python'} 08:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:05 INFO - Downloading/unpacking blobuploader==1.2.4 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:07 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:08 INFO - Downloading docopt-0.6.1.tar.gz 08:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:08 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:08 INFO - Running setup.py install for blobuploader 08:09:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:08 INFO - Running setup.py install for requests 08:09:09 INFO - Running setup.py install for docopt 08:09:09 INFO - Successfully installed blobuploader requests docopt 08:09:09 INFO - Cleaning up... 08:09:09 INFO - Return code: 0 08:09:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:09 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 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09:09 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 08:09:09 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 08:09:09 INFO - Using env: {'DISPLAY': ':0', 08:09:09 INFO - 'HOME': '/home/cltbld', 08:09:09 INFO - 'LANG': 'en_US.UTF-8', 08:09:09 INFO - 'LANGUAGE': 'en_US:en', 08:09:09 INFO - 'LOGNAME': 'cltbld', 08:09:09 INFO - 'MAIL': '/var/mail/cltbld', 08:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:09 INFO - 'MOZ_NO_REMOTE': '1', 08:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:09 INFO - 'NO_EM_RESTART': '1', 08:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:09 INFO - 'PWD': '/builds/slave/test', 08:09:09 INFO - 'SHELL': '/bin/bash', 08:09:09 INFO - 'SHLVL': '1', 08:09:09 INFO - 'TERM': 'linux', 08:09:09 INFO - 'TMOUT': '86400', 08:09:09 INFO - 'USER': 'cltbld', 08:09:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:09 INFO - '_': '/tools/buildbot/bin/python'} 08:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:09 INFO - Running setup.py (path:/tmp/pip-13SUh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:09 INFO - Running setup.py (path:/tmp/pip-wuCXvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:09 INFO - Running setup.py (path:/tmp/pip-qb5zS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:09 INFO - Running setup.py (path:/tmp/pip-PQMVMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:09 INFO - Running setup.py (path:/tmp/pip-gQHXNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:09 INFO - Running setup.py (path:/tmp/pip-VtWoHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:09 INFO - Running setup.py (path:/tmp/pip-zm6dd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:09 INFO - Running setup.py (path:/tmp/pip-uK7LEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:09 INFO - Running setup.py (path:/tmp/pip-Nh2aHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:10 INFO - Running setup.py (path:/tmp/pip-BOZYQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:10 INFO - Running setup.py (path:/tmp/pip-hNI4oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:10 INFO - Running setup.py (path:/tmp/pip-08lBlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:10 INFO - Running setup.py (path:/tmp/pip-8wglfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:10 INFO - Running setup.py (path:/tmp/pip-0J_QOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:10 INFO - Running setup.py (path:/tmp/pip-86Gv2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:10 INFO - Running setup.py (path:/tmp/pip-mISN8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:10 INFO - Running setup.py (path:/tmp/pip-dBvvtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:10 INFO - Running setup.py install for manifestparser 08:09:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:10 INFO - Running setup.py install for mozcrash 08:09:10 INFO - Running setup.py install for mozdebug 08:09:10 INFO - Running setup.py install for mozdevice 08:09:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Running setup.py install for mozfile 08:09:11 INFO - Running setup.py install for mozhttpd 08:09:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Running setup.py install for mozinfo 08:09:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Running setup.py install for mozInstall 08:09:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Running setup.py install for mozleak 08:09:11 INFO - Running setup.py install for mozlog 08:09:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Running setup.py install for moznetwork 08:09:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Running setup.py install for mozprocess 08:09:11 INFO - Running setup.py install for mozprofile 08:09:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:11 INFO - Running setup.py install for mozrunner 08:09:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:12 INFO - Running setup.py install for mozscreenshot 08:09:12 INFO - Running setup.py install for moztest 08:09:12 INFO - Running setup.py install for mozversion 08:09:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:12 INFO - Cleaning up... 08:09:12 INFO - Return code: 0 08:09:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:12 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 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09:12 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 08:09:12 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 08:09:12 INFO - Using env: {'DISPLAY': ':0', 08:09:12 INFO - 'HOME': '/home/cltbld', 08:09:12 INFO - 'LANG': 'en_US.UTF-8', 08:09:12 INFO - 'LANGUAGE': 'en_US:en', 08:09:12 INFO - 'LOGNAME': 'cltbld', 08:09:12 INFO - 'MAIL': '/var/mail/cltbld', 08:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:12 INFO - 'MOZ_NO_REMOTE': '1', 08:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:12 INFO - 'NO_EM_RESTART': '1', 08:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:12 INFO - 'PWD': '/builds/slave/test', 08:09:12 INFO - 'SHELL': '/bin/bash', 08:09:12 INFO - 'SHLVL': '1', 08:09:12 INFO - 'TERM': 'linux', 08:09:12 INFO - 'TMOUT': '86400', 08:09:12 INFO - 'USER': 'cltbld', 08:09:12 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:12 INFO - '_': '/tools/buildbot/bin/python'} 08:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:12 INFO - Running setup.py (path:/tmp/pip-zi500r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:12 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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:12 INFO - Running setup.py (path:/tmp/pip-6eWYfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:12 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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:12 INFO - Running setup.py (path:/tmp/pip-morPZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:12 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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:12 INFO - Running setup.py (path:/tmp/pip-AfwfP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:12 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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:12 INFO - Running setup.py (path:/tmp/pip-Qgf1zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:12 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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:12 INFO - Running setup.py (path:/tmp/pip-CD9rEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:13 INFO - Running setup.py (path:/tmp/pip-OS_WVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:13 INFO - Running setup.py (path:/tmp/pip-95k9rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:13 INFO - Running setup.py (path:/tmp/pip-SDjdP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:13 INFO - Running setup.py (path:/tmp/pip-vZGxsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:13 INFO - Running setup.py (path:/tmp/pip-pXpq4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:13 INFO - Running setup.py (path:/tmp/pip-kgBiii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:13 INFO - Running setup.py (path:/tmp/pip-SBfPqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:13 INFO - Running setup.py (path:/tmp/pip-k01Cu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:13 INFO - Running setup.py (path:/tmp/pip-cQqgH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:13 INFO - Running setup.py (path:/tmp/pip-qI01cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:13 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)) 08:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:13 INFO - Running setup.py (path:/tmp/pip-6akSZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:13 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)) 08:09:13 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)) 08:09:13 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)) 08:09:13 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)) 08:09:13 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)) 08:09:13 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)) 08:09:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:15 INFO - Downloading blessings-1.6.tar.gz 08:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:09:15 INFO - Installing collected packages: blessings 08:09:15 INFO - Running setup.py install for blessings 08:09:15 INFO - Successfully installed blessings 08:09:15 INFO - Cleaning up... 08:09:16 INFO - Return code: 0 08:09:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:16 INFO - Reading from file tmpfile_stdout 08:09:16 INFO - Current package versions: 08:09:16 INFO - argparse == 1.2.1 08:09:16 INFO - blessings == 1.6 08:09:16 INFO - blobuploader == 1.2.4 08:09:16 INFO - docopt == 0.6.1 08:09:16 INFO - manifestparser == 1.1 08:09:16 INFO - mozInstall == 1.12 08:09:16 INFO - mozcrash == 0.17 08:09:16 INFO - mozdebug == 0.1 08:09:16 INFO - mozdevice == 0.48 08:09:16 INFO - mozfile == 1.2 08:09:16 INFO - mozhttpd == 0.7 08:09:16 INFO - mozinfo == 0.9 08:09:16 INFO - mozleak == 0.1 08:09:16 INFO - mozlog == 3.1 08:09:16 INFO - moznetwork == 0.27 08:09:16 INFO - mozprocess == 0.22 08:09:16 INFO - mozprofile == 0.28 08:09:16 INFO - mozrunner == 6.11 08:09:16 INFO - mozscreenshot == 0.1 08:09:16 INFO - mozsystemmonitor == 0.0 08:09:16 INFO - moztest == 0.7 08:09:16 INFO - mozversion == 1.4 08:09:16 INFO - psutil == 3.1.1 08:09:16 INFO - requests == 1.2.3 08:09:16 INFO - wsgiref == 0.1.2 08:09:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:16 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 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09:16 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 08:09:16 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 08:09:16 INFO - Using env: {'DISPLAY': ':0', 08:09:16 INFO - 'HOME': '/home/cltbld', 08:09:16 INFO - 'LANG': 'en_US.UTF-8', 08:09:16 INFO - 'LANGUAGE': 'en_US:en', 08:09:16 INFO - 'LOGNAME': 'cltbld', 08:09:16 INFO - 'MAIL': '/var/mail/cltbld', 08:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:16 INFO - 'MOZ_NO_REMOTE': '1', 08:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:16 INFO - 'NO_EM_RESTART': '1', 08:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:16 INFO - 'PWD': '/builds/slave/test', 08:09:16 INFO - 'SHELL': '/bin/bash', 08:09:16 INFO - 'SHLVL': '1', 08:09:16 INFO - 'TERM': 'linux', 08:09:16 INFO - 'TMOUT': '86400', 08:09:16 INFO - 'USER': 'cltbld', 08:09:16 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:16 INFO - '_': '/tools/buildbot/bin/python'} 08:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 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)) 08:09:16 INFO - Cleaning up... 08:09:16 INFO - Return code: 0 08:09:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09: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', 'jsonschema'] in /builds/slave/test/build 08:09: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 jsonschema 08:09:16 INFO - Using env: {'DISPLAY': ':0', 08:09:16 INFO - 'HOME': '/home/cltbld', 08:09:16 INFO - 'LANG': 'en_US.UTF-8', 08:09:16 INFO - 'LANGUAGE': 'en_US:en', 08:09:16 INFO - 'LOGNAME': 'cltbld', 08:09:16 INFO - 'MAIL': '/var/mail/cltbld', 08:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:16 INFO - 'MOZ_NO_REMOTE': '1', 08:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:16 INFO - 'NO_EM_RESTART': '1', 08:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:16 INFO - 'PWD': '/builds/slave/test', 08:09:16 INFO - 'SHELL': '/bin/bash', 08:09:16 INFO - 'SHLVL': '1', 08:09:16 INFO - 'TERM': 'linux', 08:09:16 INFO - 'TMOUT': '86400', 08:09:16 INFO - 'USER': 'cltbld', 08:09:16 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:16 INFO - '_': '/tools/buildbot/bin/python'} 08:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:16 INFO - Downloading/unpacking jsonschema 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:18 INFO - Downloading/unpacking functools32 (from jsonschema) 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:18 INFO - warning: no files found matching '*.txt' 08:09:18 INFO - no previously-included directories found matching 'build' 08:09:18 INFO - no previously-included directories found matching 'dist' 08:09:18 INFO - no previously-included directories found matching '.git*' 08:09:18 INFO - Installing collected packages: jsonschema, functools32 08:09:18 INFO - Running setup.py install for functools32 08:09:18 INFO - warning: no files found matching '*.txt' 08:09:18 INFO - no previously-included directories found matching 'build' 08:09:18 INFO - no previously-included directories found matching 'dist' 08:09:18 INFO - no previously-included directories found matching '.git*' 08:09:18 INFO - Successfully installed jsonschema functools32 08:09:18 INFO - Cleaning up... 08:09:19 INFO - Return code: 0 08:09:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:09:19 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')]} 08:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:19 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')]} 08:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:19 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 0x7f7e93543f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192e9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', '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 08:09:19 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 08:09:19 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 08:09:19 INFO - Using env: {'DISPLAY': ':0', 08:09:19 INFO - 'HOME': '/home/cltbld', 08:09:19 INFO - 'LANG': 'en_US.UTF-8', 08:09:19 INFO - 'LANGUAGE': 'en_US:en', 08:09:19 INFO - 'LOGNAME': 'cltbld', 08:09:19 INFO - 'MAIL': '/var/mail/cltbld', 08:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:19 INFO - 'MOZ_NO_REMOTE': '1', 08:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:19 INFO - 'NO_EM_RESTART': '1', 08:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:19 INFO - 'PWD': '/builds/slave/test', 08:09:19 INFO - 'SHELL': '/bin/bash', 08:09:19 INFO - 'SHLVL': '1', 08:09:19 INFO - 'TERM': 'linux', 08:09:19 INFO - 'TMOUT': '86400', 08:09:19 INFO - 'USER': 'cltbld', 08:09:19 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:19 INFO - '_': '/tools/buildbot/bin/python'} 08:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:19 INFO - Downloading/unpacking flake8 08:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:09:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:09:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 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 08:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:09:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:09:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:09:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:09:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:09:21 INFO - no previously-included directories found matching 'docs/_build' 08:09:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:09:21 INFO - Running setup.py install for pep8 08:09:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:09:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:09:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:09:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:09:21 INFO - no previously-included directories found matching 'docs/_build' 08:09:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:09:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:09:21 INFO - Cleaning up... 08:09:22 INFO - Return code: 0 08:09:22 INFO - Running post-action listener: _resource_record_post_action 08:09:22 INFO - Running post-action listener: _start_resource_monitoring 08:09:22 INFO - Starting resource monitoring. 08:09:22 INFO - ##### 08:09:22 INFO - ##### Finished create-virtualenv step (success) 08:09:22 INFO - ##### 08:09:22 INFO - ##### 08:09:22 INFO - ##### Running install step. 08:09:22 INFO - ##### 08:09:22 INFO - Running pre-action listener: _resource_record_pre_action 08:09:22 INFO - Running main action method: install 08:09:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:22 INFO - Reading from file tmpfile_stdout 08:09:22 INFO - Detecting whether we're running mozinstall >=1.0... 08:09:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:09:22 INFO - Reading from file tmpfile_stdout 08:09:22 INFO - Output received: 08:09:22 INFO - Usage: mozinstall [options] installer 08:09:22 INFO - Options: 08:09:22 INFO - -h, --help show this help message and exit 08:09:22 INFO - -d DEST, --destination=DEST 08:09:22 INFO - Directory to install application into. [default: 08:09:22 INFO - "/builds/slave/test"] 08:09:22 INFO - --app=APP Application being installed. [default: firefox] 08:09:22 INFO - mkdir: /builds/slave/test/build/application 08:09:22 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'] 08:09:22 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 08:09:38 INFO - Reading from file tmpfile_stdout 08:09:38 INFO - Output received: 08:09:38 INFO - /builds/slave/test/build/application/firefox/firefox 08:09:38 INFO - Running post-action listener: _resource_record_post_action 08:09:38 INFO - ##### 08:09:38 INFO - ##### Finished install step (success) 08:09:38 INFO - ##### 08:09:38 INFO - ##### 08:09:38 INFO - ##### Running run-tests step. 08:09:38 INFO - ##### 08:09:38 INFO - Running pre-action listener: _resource_record_pre_action 08:09:38 INFO - Running main action method: run_tests 08:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:09:38 INFO - Python 2.7.3 08:09:38 INFO - Return code: 0 08:09:38 INFO - grabbing minidump binary from tooltool 08:09:38 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')]} 08:09:38 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bd810>, '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 0x19be710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19beba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:09:38 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 08:09:38 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 08:09:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:09:39 INFO - Return code: 0 08:09:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:09:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:09:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:09:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:09:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:09:39 INFO - Return code: 0 08:09:39 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/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:09:39 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/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:09:39 INFO - Using env: {'DISPLAY': ':0', 08:09:39 INFO - 'HOME': '/home/cltbld', 08:09:39 INFO - 'LANG': 'en_US.UTF-8', 08:09:39 INFO - 'LANGUAGE': 'en_US:en', 08:09:39 INFO - 'LOGNAME': 'cltbld', 08:09:39 INFO - 'MAIL': '/var/mail/cltbld', 08:09:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:09:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:39 INFO - 'MOZ_NO_REMOTE': '1', 08:09:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:09:39 INFO - 'NO_EM_RESTART': '1', 08:09:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:39 INFO - 'PWD': '/builds/slave/test', 08:09:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:09:39 INFO - 'SHELL': '/bin/bash', 08:09:39 INFO - 'SHLVL': '1', 08:09:39 INFO - 'TERM': 'linux', 08:09:39 INFO - 'TMOUT': '86400', 08:09:39 INFO - 'USER': 'cltbld', 08:09:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035', 08:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:39 INFO - '_': '/tools/buildbot/bin/python'} 08:09:39 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/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:09:40 INFO - mozversion application_buildid: 20160506052037 08:09:40 INFO - mozversion application_changeset: 19a1743ceb2e035e571012e88d25275ce627b925 08:09:40 INFO - mozversion application_display_name: Nightly 08:09:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:09:40 INFO - mozversion application_name: Firefox 08:09:40 INFO - mozversion application_remotingname: firefox 08:09:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:09:40 INFO - mozversion application_vendor: Mozilla 08:09:40 INFO - mozversion application_version: 49.0a1 08:09:40 INFO - mozversion platform_buildid: 20160506052037 08:09:40 INFO - mozversion platform_changeset: 19a1743ceb2e035e571012e88d25275ce627b925 08:09:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:09:40 INFO - mozversion platform_version: 49.0a1 08:09:40 INFO - using testdate: 1462547380 08:09:40 INFO - actual date: 1462547380 08:09:40 INFO - starting webserver on 'localhost:52235' 08:09:40 INFO - SUITE-START | Running 2 tests 08:09:40 INFO - TEST-START | dromaeo_css 08:09:40 INFO - operating with platform_type : linux_ 08:09:40 INFO - Initialising browser for dromaeo_css test... 08:09:40 INFO - TEST-INFO | started process 18995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7NfbI/profile http://localhost:52235/getInfo.html) 08:09:44 INFO - PROCESS | 18995 | __metrics Screen width/height:1600/1200 08:09:44 INFO - PROCESS | 18995 | colorDepth:24 08:09:44 INFO - PROCESS | 18995 | Browser inner width/height: 1024/697 08:09:44 INFO - PROCESS | 18995 | __metrics 08:09:45 ERROR - PROCESS | 18995 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:09:46 INFO - TEST-INFO | 18995: exit 0 08:09:46 INFO - Browser initialized. 08:09:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:09:46 INFO - TEST-INFO | started process 19077 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7NfbI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:58 INFO - PROCESS | 19077 | RSS: Main: 152748032 08:09:58 INFO - PROCESS | 19077 | 08:10:49 INFO - PROCESS | 19077 | 1462547449093 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] 08:12:56 INFO - PROCESS | 19077 | Cycle 1(1): loaded http://localhost:52235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52235/tests/dromaeo/cssquery-ext.html) 08:12:57 INFO - PROCESS | 19077 | RSS: Main: 254930944 08:12:57 INFO - PROCESS | 19077 | 08:16:00 INFO - PROCESS | 19077 | Cycle 1(1): loaded http://localhost:52235/tests/dromaeo/cssquery-ext.html (next: http://localhost:52235/tests/dromaeo/cssquery-jquery.html) 08:16:00 INFO - PROCESS | 19077 | RSS: Main: 239128576 08:16:00 INFO - PROCESS | 19077 | 08:19:02 INFO - PROCESS | 19077 | Cycle 1(1): loaded http://localhost:52235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52235/tests/dromaeo/cssquery-mootools.html) 08:19:03 INFO - PROCESS | 19077 | RSS: Main: 232255488 08:19:03 INFO - PROCESS | 19077 | 08:22:05 INFO - PROCESS | 19077 | Cycle 1(1): loaded http://localhost:52235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52235/tests/dromaeo/cssquery-prototype.html) 08:22:05 INFO - PROCESS | 19077 | RSS: Main: 222650368 08:22:05 INFO - PROCESS | 19077 | 08:25:08 INFO - PROCESS | 19077 | Cycle 1(1): loaded http://localhost:52235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52235/tests/dromaeo/cssquery-yui.html) 08:25:09 INFO - PROCESS | 19077 | RSS: Main: 201138176 08:25:09 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | Cycle 1(1): loaded http://localhost:52235/tests/dromaeo/cssquery-yui.html (next: http://localhost:52235/tests/dromaeo/cssquery-dojo.html) 08:28:11 INFO - PROCESS | 19077 | RSS: Main: 263077888 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | __start_tp_report 08:28:11 INFO - PROCESS | 19077 | _x_x_mozilla_page_load 08:28:11 INFO - PROCESS | 19077 | _x_x_mozilla_page_load_details 08:28:11 INFO - PROCESS | 19077 | |i|pagename|runs| 08:28:11 INFO - PROCESS | 19077 | |0;dojo.html;3132.5927629408175;3142.8585713642883;3146.1190866557363;3180.1890517917927;3195.55262263283;5302;5334.279872217249;5360.678359298445;5443.237946687462;5464.754086066127;5383;5389.461053894609;5395.379531353896;5424.945750542493;5469.453054694529;4829.323894654743;4842.12841688496;5052.065367906949;5089.643724939256;5101.285819985205;5021.673591216578;5023.623228257877;5066.721330326849;5080.263361812535;5087.643864929438;4736.502667219944;4855.295982082596;4892.4989010110785;4903.730294833783;4919.532644398776;4871.098846713369;4889.48660390661;4948.0846043481715;4961.751912404401;5007.54932056113;4878.341423907763;4924.975375123101;4951.752412379403;4959.975200124012;4987.977464619744;5198.012377648235;5289.894202115975;5316.6544174628525;5340.813071542516;5390.1914712793005;5793.8841223175305;5832.708364581797;5853.9414605854245;5859.091840764646;5881.500072493843;5632.295963004624;5732.3121225453215;5855.677937713466;5869.9706501467645;5932.466078052996;5327.600429967729;5401.594880383947;5446.319210098738;5463.562914966793;5481.095619222863;5923.289205295306;5957.523398128139;5960.225170727865;5979.133025711248;5979.372165922516;5554.361248456479;5627.549796016309;5672.404397538282;5732.283464566929;5798.072308430631;5787.363390027093;5787.768489260467;5791.305043394819;5800.970995144998;5829.183914252008;5762.1068734346245;5766.855828604234;5773.624714393551;5776.68228247442;5780.335261444903;5630.887382252332;5738.512226460798;5744.913826292609;5755.107958266475;5778.451047150514;4355.60799528044;4357.455318085239;4363.890902727394;4380.759058251763;4406.074724307867;3897.240038192582;3904.39481880309;3927.0378757204667;3952.940705889414;3962.603739626072;5281.498257665516;5287.471252874681;5398.892022159614;5419.078756611386;5441.129419292894;5835.912461313083;5849.5027922626205;5851.590388672837;5853.209816674779;5875.529957603381;5261.763220655003;5467.425920278393;5483.204935284361;5494.642848214933;5495.120780675153;4942.036302920896;4966.925496117561;4972.925406118911;4987;4987.825426110141;5983.940160598426;6011.158437818709;6014.669193194416;6019.097135429633;6031.547633927473;5617.297837770278;5748.367679555329;5844.620099693507;5852.502537284208;5857;5925.9407405925385;5995.2206213192;5998;6018;6038.9396106038375;5679.9432005681065;5700.629459085146;5703.942960570341;5706.400827913009;5713.285839270091;5685.175649530712;5802;5813.82558523245;5821.854453638693;5890.911636325544;5677.205191273142;5680.147977803363;5700.230468886647;5720.771169153188;5729.341125770423;5607.8878422432135;5657.151427285776;5671.8865622688145;5673.943260567342;5685.829425117253;5327.067763141357;5473.343198816167;5502.119660854244;5513.255710479034;5516.365617953987;5769.711514424346;5802.071668533014;5818.6217895836635;5819.76720931158;5829.329627092939;5581.218629391808;5616.3260408749575;5625.409332020243;5650.23721797552;5663.9716801415725 08:28:11 INFO - PROCESS | 19077 | |1;ext.html;589.4311988930681;641.1024565608151;642.9132067170932;643.202428988055;657.3393739292012;31122.37755244894;34153.65846341539;41880;42595.57404425955;42761.14477710444;43735;44016.55983440165;44176.558234417644;44198.55801441985;44262;8754.3871928965;8912.64349426022;8918.866217006751;8929.910700893006;8933.062028487013;8785.516796576187;8826.823463530725;8904.910950890506;8905.554722263892;8913.59888805002;8885.644574217024;8937.865932010993;8938.64245430182;8944.776380590505;8948.463092214455;8863;8902.287816974627;8918.241949434307;8918.955405222998;8922.420042697204;8800.07599202081;8825.205731484197;8886.555672216427;8902.821943561157;8905.465672059632;41204.79397602993;41557.376639350434;41572.37641435381;41606.79196604028;41622.58377416248;3770.20825626616;3794.0135564753086;3814.6757525610356;3866.5940076291868;4112.979435102835;31774;32060.519092213635;32292.35415291681;32626;32743.6725632743;4319.740815551046;4434.1131773645075;4436.866893993185;4456.844010459618;4482.798274077674;310641;311143;311914;312853;313304;55244.72377638086;55684.72157639267;56038.71980640071;56074;56131.158032629544;36562.35469403883;37449.62550374462;37459.62540374616;39089.609103908595;39091.21817563633;17538.210780514906;17771.378001769877;17803.643927121386;17815.376461823773;17826.46520604384;13544.729105417837;13654;13664.658383540293;13795.448182072605;13800.102993305403;19304.486859198616;19507.122179501956;19582.902085489768;19639.214431422584;19651.213951442067;7322.487425880244;7403.51877127985;7505.324520793155;7521.022267105242;7522.285382888617;1033.7725700345911;1046.7644779924456;1081.2161183142316;1149.3850789827402;1189.3339729751367;31850.2037449061;32228.777122287585;32615.18462038468;33107;33268.50097248543;1126.1047467263545;1174.0548858169257;1177.464253764535;1193.838831757719;1239.293602646483;17250.309987600605;17426;17474.388396406066;17502.299908003537;17555.912220438815;522490;529639;529977;534100;534996;57543.424565753805;58029.709851450476;58040;58124;58138;1301.9609411717474;1389.3400634698437;1394.6443656867636;1408.9929550352183;1418.5389748331834;32046.6795332053;33617.32765344631;33817.83091084529;34069.318613628086;34189;49345.753271233414;49977.25034124418;49989;50006.74996624994;50013.24980125374;347136;347663;351005;351030;351386;23286.76713232846;26103.477930440906;26133.607995879698;27456.45087098287;29831.701682982894;45309.32036019397;46512.767436162605;47245.76377118092;47354.52645473501;47482.575522732994;40018.199636007696;40083;40291.1941761169;40607.796961015;41149.588504114574;39012.80493597514;39070.60929390784;39584.40623390594;39653.603463966145;39816.20367592574;40825.795871020455;42988.78505607452;43589.34615980699;43634.56365436305;45144;34916.82541587378;37224;37323.06692332713;37606;37747.43378849264;28057.859710701316;28223.57664634991;28409.857950710113;28675.713242867303;29016.854915725286 08:28:11 INFO - PROCESS | 19077 | |2;jquery.html;382.678550017985;392.6113147983497;392.9115948911493;393.807034553069;418.0343406730453;14791.482298119568;17142.657146857055;17659.29362825487;19095.331663391786;19102.235910563577;18281.451556453296;18566.35017774378;18961;19069.427917162473;19085.332013379535;5510.8071217507295;5653.095504719246;5658.34928983166;5672.581854536366;5695.060315048033;4110.136871257025;5554.1946417769395;5606.21512988182;5884.794032208873;6173.228346456693;6190.473809726179;6243.2508099027955;6250.687465626723;6261.812145635638;6270.247570291572;5898.970505147489;6252.718627661743;6304.936950630481;6308.463780578656;6308.558400911938;4828.710277383333;5520.972395138038;5547.44525547446;5553.7778488860395;5561.916571251434;13364.198148111145;13572.796408053888;13846;13873.4450621975;13876.098053626481;2266.444721043355;2315.8263130265295;2321.9535609287886;2560.46230291639;2991.9401611967637;9279;9691.854622180674;9725.65960191397;9875.259355548362;10386.324888882198;1790.9462716118537;1882.6799444094536;1907.2085084689836;1937;2058.043010000357;74436;84177.57911210466;84868;86969.56515217447;88823.55588222083;10325.32885362449;10779.946100269448;11129.610463633733;11173.553057877594;11346.3759493227;15390;15455.690886182214;15460.304286307142;15518.456854010055;15537.145456999973;8359.58202089893;8627.95686021566;8677.045524992245;8732;8798.560071996375;8356.164383561596;8426;8508.617112229964;8524.389883444821;8648.956755216184;4622.491525932145;4655.976720116378;4680.672352935262;4732.763361831895;4808.08646357191;1833.7066069428827;1883.7771429712795;1968.4389948864505;1993.5414854583528;2004.5690176611806;957.8275910336067;1041.5833666533297;1084.1110289562482;1125.8423820665114;1135.5230803062568;17580.560485987953;18554.44336669902;19118.2352705893;19299;19469.8053019468;1013.8884722680499;1027.5992362978445;1029.361795686664;1077.2459278505078;1082.3073233130804;10377.740556486147;10858.402787846586;10885.564577416973;10931.781364372828;10945.343279403261;509715;519619;520300;533404;538212;63958.68020659867;77555.22444775593;81213.59393203114;87308;100388;1065.9040686338267;1066.2323127348297;1104.469854469859;1108.0747575774158;1109.8890110988998;17450.82549174492;17585;17861.28554857791;17944.641107178046;17957.371491997717;41233;41341.37987930243;42691.78654106709;43869.341959871264;44348.556514434444;59523.40476595179;59804.70097649484;59853;60656.69671651613;61949;7552.811179720551;7755.883661745192;8020.398470114648;8550.444221125606;8781.560921954004;9808.362456440309;13601.455941762219;13822.30888455553;13916.443342266197;13952.79070813959;11967.64097077089;12006.519739210684;12062.638120856389;12195.451204695988;12271.447784849524;11382.430878456209;11815.468303926516;11965.162438629213;11979.101567382308;12089.39553022363;12219.816702749644;12245.57140500114;12402.25586464815;12428.316442595744;12578.30819304947;7741.92258077412;8214.4660597061;8238.670453181805;8246.38152138604;8450.746477605682;10386.42874641902;10762.677119686421;11113.555457781598;11379.146564007568;11652.766944661229 08:28:11 INFO - PROCESS | 19077 | |3;mootools.html;1325.86888740574;1471.8601732835384;1523.0785374848217;1628.633557449574;1788.6243888783347;7684.308412242897;8036.517808931469;8105.027396712403;8263.586820658958;8313.833723325544;7820.8044798880055;8003.239692229244;8007.279344858976;8010.799730006753;8026.799330016753;2247.8538894971775;2286.7598902115205;2289.072925465189;2291.747907730148;2295.8737269450176;2247.505548779266;2260.446190683285;2260.5026894083276;2263.162629826966;2286.451251699597;2252.166698321615;2263.2531264682616;2267.206477732797;2276.078188333719;2276.203328834903;2246.4945387287894;2250.954980900381;2252.2117258959397;2255.3684968208845;2256.627656436686;2234.821214302868;2241.7085778848646;2242.55148970205;2245.1468441991938;2246.3148739634403;7809.726659566888;8050.355971522265;8112.716054938049;8143.267105960433;8180.7954801130445;4926.852194434173;5022.573081288095;5026.346574945271;5048.5203905628905;5052.621053420971;3250.398676244902;3274.9017529474154;3330.383878982396;3388.762786604939;3442.397580423416;1118.7930232906856;1144.7710457908452;1157.2477889371892;1161.680537852089;1167.830664553635;10987;10997.395143267117;11039.779204415947;11084.057855082328;11142.387168705718;4222.894427639334;4263.509696384894;4313.180495705806;4349.499807522176;4360.847370342023;9059.229965452878;9074.820273364421;9080.727578172533;9131.77170570741;9131.863022054675;4987.750612469362;4988.376452943382;5016.322796422436;5021.171506701354;5039.3448851649055;4086.7956602169775;4537.886552836139;4593.908121837611;4726.95273047272;4760.262159365304;3921.4117882317883;3944.6647035001774;4014.7992600369867;4018.678505719535;4046.433499310099;1288.6391810293132;1313.4680454415977;1319.3403298350825;1321.2270821569298;1339.2567125245387;788.6727008291609;941.3645789092335;942.533445944262;942.604106275366;980.1423754215061;5417.214503896913;5607.467290607386;5664.943350566524;5761.366249712528;5777.248957635565;482.80205115903016;492.278811541094;496.1590104772448;504.17567277500814;554.3763266325384;3783.564890037626;3913.0608653922855;3992.5208974923194;4026.295398305285;4029.17401932609;9712.465814380277;10097;10141;10141.2394070445;10153.340032897839;9962.452065136487;9972.501374931224;10070.597176112873;10101.19190464761;10111.775409409918;7310.780676579711;7630.236976302325;7684.500507467109;7778.299953004257;7830.960845195737;5800.622959507618;5812.5349972001295;5897.233359663216;5926.555508336805;5946.405359464192;9054.320925930713;9304.16262536375;9528.618855245713;9631.807363852544;9800.215982721507;9394.67118650844;9566.043395660377;9684.854727178958;9899.406035637885;9914.752131196778;6579.309172537006;6952.374286314256;7225.5664660118455;7453.440991925519;7603.809904752426;8025.9598702006115;8056.879146812919;8088.069871964564;8153.470024448629;8161.061477929877;7196.136463624399;7266;7378.631068446663;7390.593517356597;7477.140128885248;7019.227884932551;7181.676824542804;7267.091613548307;7368.705251789868;7414.703411863465;6706.195256569243;6723.361280678328;6727.091842601186;6729.0915726376315;6738.022986666808;4638.512956139692;4720.103180395851;4721.881952951204;4739.312799643963;4830.661853670209;4768.308595253599;4784.880377990439;4785.880352991203;4808.374911261513;4838.516148385274 08:28:11 INFO - PROCESS | 19077 | |4;prototype.html;330.5256956267756;332.380111092812;335.15875153365056;337.9357921994821;345.87029863801075;5085.567726743231;5225.294585230993;5287.788488460457;5321.1220148675475;5338.119210330296;5243.947560524393;5261.105612045952;5266.657667251636;5276.947230527693;5312.4687531246855;4135.379693046037;4135.834566617346;4139.172165566891;4140.3168477201225;4144.502659680843;3946.605339466045;3979.9652090456543;4098.6516146127615;4118.629323360897;4133.276676581601;4077.408775727518;4081.7346872453345;4083.448734420845;4120.196561670476;4124.463819703449;4067.5525692173837;4109.178164367138;4123.9792501674265;4129.421880936671;4160.687948403882;3464.8440820163155;3706.5552133743854;3707.35121353762;3708.3695771718603;3717.2565486902727;5044.39467263927;5050.39395272566;5058;5085.847424577269;5090.134677104901;4686.414198225222;4690.0854333405;4716.9292460613115;4717.528247175289;4740.786664600101;5367.953249116424;5397.919031214535;5501.504864562168;5505.6696598203835;5527.972360138214;3962.7622342659533;3982.721209515335;3984.721069525134;3985.9800700996598;3992.7205095643317;5707.343655479632;5810.33181184165;5815.563832712563;5820.417958204188;5834.154047663065;3971.126352202511;4067.1255680028935;4067.901666550015;4074.4295798588223;4076.9592304077173;4093.6520395766693;4097.610726980932;4098;4102.774347410861;4105.527864295585;4662.930056049094;4671.696339737974;4675.4155730533685;4700.435947686299;4720.598749106296;5569.972150139304;5585.1901474286005;5613.410591887875;5641.52047075995;5644.238027866268;1390.9289846817894;1421.3461807568635;1421.5451055662088;1426.4793350427226;1426.8216472940883;988.5798535622332;994.5226291380178;1013.2906965124442;1013.6046941692746;1015.2690063154517;4985.8005679772405;4991.675541089818;5009;5059.671121377099;5077.492250774893;5349.117395629678;5363.693611746909;5365.517103460629;5384.246205531229;5394.622376433612;4990.6257030722845;5017.1721665924715;5058.4435712071645;5067.290579318898;5349.304590403301;2926.341573146025;2930.8827646893888;2932.3548819259727;2935.442265969459;2973.6728959814404;5682.914756278659;5711.6292089898125;5755.510781583613;5771.5571107223195;5777.555611097226;5475.945240547623;5622.690752008597;5629.634073785191;5647.37878833328;5662.66024038559;5578.219049333096;5694;5717.656940583578;5788.45009724084;5813.593048486735;5335.918991593359;5389.844062375081;5450.754716037867;5490.176473529003;5620.747066382104;5487.341519017744;5495.2856128703015;5510.504054635102;5514.61397702157;5539.19681646151;5668.291463567054;5695;5731.7420716067045;5809.419058094157;5814.389489103583;3669.3578623740204;3862.1503269280715;3909.237698648736;4018.477597912253;4032.2540330039037;5624.240727501735;5722.513586345207;5722.713864306685;5729.994501924293;5737.684427356536;5532.474414930575;5535.446455354593;5559.16612508127;5569.777208911598;5580.665160090408;5395.78416863321;5482.753276102505;5498.725063746877;5505.366882808528;5520.420355862577;5278.76245568958;5298.682079075268;5331.5201631853315;5339;5349.742810439466;5276.894462110659;5517;5520.337559492887;5540.279763630702;5561.304836895388;5359.812406565751;5359.839204823861;5381.246625472359;5381.744044852964;5436.157395603688 08:28:11 INFO - PROCESS | 19077 | |5;yui.html;788.7791418402845;793.8134538383482;821.8315245374699;838.4131108224244;853.4111463090469;14048.929755351222;15143.772843407343;15284.465043723472;15370.308336124874;15383.846161538395;12913.096083274175;13931.860681393184;14272.215028173447;14278.571642850706;14333.641658958531;813.1827513349106;816.3020617372135;828.5360198288978;832.0306842528478;834.3158609939821;824.5794644731201;829.4774292195908;829.8008477965275;830.0744669693279;831;867.6312567158964;871.7123349294718;879.8900137482815;879.9692010779625;881.6120906800987;867.1155421470097;870.1994165367877;874.2743522876029;874.4316194473579;875.1336177184605;636.0522820996682;636.4749082007316;637.7895294552786;638.9297177310492;639.1212083385345;13081.215127092404;13582.660433489144;13593.796093058612;13602.795958060637;13632.727345453035;12486.313252771093;12524.81212781809;12540.435680394405;12554;12624.55814046504;10780.29928054674;10780.29928054674;10781.730456738565;10793.136549076133;11152.832707509393;3755.6244375562496;3803.7527560708463;3845.65389114981;4348.086901750637;4467.374567560544;44388;53006;54331.72834135844;54340.72829635866;55178.44821551813;10848.620298289521;11041.227114102096;11043.447827608587;11145.832812507819;11411.030062444619;19043.42869713911;19127.617447651246;19149.138288777038;19164.329248476515;19199;8505.532195729294;8560.186782255676;8815.25070369013;8843.425177363451;8858.247049000907;4812.663113582085;5014.84955451337;5026.497350265017;5175.171972484384;5175.715335656575;3378.9831050844587;3439.8968030959113;3461.273132642123;3475.461303497962;3476.7392445566684;643.1317721076603;646.1696719715237;649.6849028221309;661.8014595621295;666.9899951500731;672.3209558346106;678.2064983968768;712.3482013957274;715.4169351978121;727.31268950842;9877.16044136242;9882.604695812088;9929.156021738088;9991.150752186146;10047.698569042941;665.3180489997791;693.729445516249;699.433458898294;700.5131433653562;723.3164659396924;4580.152671755736;4666.8133274668635;4767.165745994506;4771.498992605726;4771.832985845548;59853;63660.36339636544;63743.36256637374;63919.44322227152;63958;55918.720406397704;56401.71799140978;57068;57171;58805;38041.42937855935;46691.5330846694;48830;53080;53606.463935360924;9693.951530242304;9743.123118919331;9752.268579856682;9926;10002.149817265614;34456.82771586126;35699;35946.28107437785;35968.460473092404;36037.639623604475;41808.79095604503;47930.281045785036;48310.55068347815;48429.5157048425;48650;11906.52373905034;12194.695132621755;12242.449089791158;12255.264684118982;12465.504139503317;13720.108192967426;13928.373223204777;13980.510682126076;14016.649583760487;14056.086354386933;13123.60629181126;13163.53927612529;13164.078514504276;13478.393472293574;13510.93244533771;13099.541516046893;13218.206907585576;13340.399682014138;13351.666208344868;13488.32558372097;13140.80288795688;13280.468781248643;13298.734025319245;13306;14036.929815350857;10557.683269501927;10558.155347572329;10943.726406839574;11297.209195356247;11373.31760094397;11036.075440008564;11048.281861678965;11051.226414150933;11494.827577586175;11509.13681473876 08:28:11 INFO - PROCESS | 19077 | __end_tp_report 08:28:11 INFO - PROCESS | 19077 | __start_cc_report 08:28:11 INFO - PROCESS | 19077 | _x_x_mozilla_cycle_collect,838 08:28:11 INFO - PROCESS | 19077 | __end_cc_report 08:28:11 INFO - PROCESS | 19077 | __startTimestamp1462548491828__endTimestamp 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | ------- Summary: start ------- 08:28:11 INFO - PROCESS | 19077 | Number of tests: 6 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | [#0] dojo.html Cycles:165 Average:5379.90 Median:5607.89 stddev:614.85 (11.0%) stddev-sans-first:590.92 08:28:11 INFO - PROCESS | 19077 | Values: 3132.6 3142.9 3146.1 3180.2 3195.6 5302.0 5334.3 5360.7 5443.2 5464.8 5383.0 5389.5 5395.4 5424.9 5469.5 4829.3 4842.1 5052.1 5089.6 5101.3 5021.7 5023.6 5066.7 5080.3 5087.6 4736.5 4855.3 4892.5 4903.7 4919.5 4871.1 4889.5 4948.1 4961.8 5007.5 4878.3 4925.0 4951.8 4960.0 4988.0 5198.0 5289.9 5316.7 5340.8 5390.2 5793.9 5832.7 5853.9 5859.1 5881.5 5632.3 5732.3 5855.7 5870.0 5932.5 5327.6 5401.6 5446.3 5463.6 5481.1 5923.3 5957.5 5960.2 5979.1 5979.4 5554.4 5627.5 5672.4 5732.3 5798.1 5787.4 5787.8 5791.3 5801.0 5829.2 5762.1 5766.9 5773.6 5776.7 5780.3 5630.9 5738.5 5744.9 5755.1 5778.5 4355.6 4357.5 4363.9 4380.8 4406.1 3897.2 3904.4 3927.0 3952.9 3962.6 5281.5 5287.5 5398.9 5419.1 5441.1 5835.9 5849.5 5851.6 5853.2 5875.5 5261.8 5467.4 5483.2 5494.6 5495.1 4942.0 4966.9 4972.9 4987.0 4987.8 5983.9 6011.2 6014.7 6019.1 6031.5 5617.3 5748.4 5844.6 5852.5 5857.0 5925.9 5995.2 5998.0 6018.0 6038.9 5679.9 5700.6 5703.9 5706.4 5713.3 5685.2 5802.0 5813.8 5821.9 5890.9 5677.2 5680.1 5700.2 5720.8 5729.3 5607.9 5657.2 5671.9 5673.9 5685.8 5327.1 5473.3 5502.1 5513.3 5516.4 5769.7 5802.1 5818.6 5819.8 5829.3 5581.2 5616.3 5625.4 5650.2 5664.0 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | [#1] ext.html Cycles:180 Average:55856.25 Median:39917.20 stddev:108193.27 (271.0%) stddev-sans-first:108417.21 08:28:11 INFO - PROCESS | 19077 | Values: 589.4 641.1 642.9 643.2 657.3 31122.4 34153.7 41880.0 42595.6 42761.1 43735.0 44016.6 44176.6 44198.6 44262.0 8754.4 8912.6 8918.9 8929.9 8933.1 8785.5 8826.8 8904.9 8905.6 8913.6 8885.6 8937.9 8938.6 8944.8 8948.5 8863.0 8902.3 8918.2 8919.0 8922.4 8800.1 8825.2 8886.6 8902.8 8905.5 41204.8 41557.4 41572.4 41606.8 41622.6 3770.2 3794.0 3814.7 3866.6 4113.0 31774.0 32060.5 32292.4 32626.0 32743.7 4319.7 4434.1 4436.9 4456.8 4482.8 310641.0 311143.0 311914.0 312853.0 313304.0 55244.7 55684.7 56038.7 56074.0 56131.2 36562.4 37449.6 37459.6 39089.6 39091.2 17538.2 17771.4 17803.6 17815.4 17826.5 13544.7 13654.0 13664.7 13795.4 13800.1 19304.5 19507.1 19582.9 19639.2 19651.2 7322.5 7403.5 7505.3 7521.0 7522.3 1033.8 1046.8 1081.2 1149.4 1189.3 31850.2 32228.8 32615.2 33107.0 33268.5 1126.1 1174.1 1177.5 1193.8 1239.3 17250.3 17426.0 17474.4 17502.3 17555.9 522490.0 529639.0 529977.0 534100.0 534996.0 57543.4 58029.7 58040.0 58124.0 58138.0 1302.0 1389.3 1394.6 1409.0 1418.5 32046.7 33617.3 33817.8 34069.3 34189.0 49345.8 49977.3 49989.0 50006.7 50013.2 347136.0 347663.0 351005.0 351030.0 351386.0 23286.8 26103.5 26133.6 27456.5 29831.7 45309.3 46512.8 47245.8 47354.5 47482.6 40018.2 40083.0 40291.2 40607.8 41149.6 39012.8 39070.6 39584.4 39653.6 39816.2 40825.8 42988.8 43589.3 43634.6 45144.0 34916.8 37224.0 37323.1 37606.0 37747.4 28057.9 28223.6 28409.9 28675.7 29016.9 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | [#2] jquery.html Cycles:180 Average:29564.81 Median:2291.14 stddev:86230.61 (3763.7%) stddev-sans-first:86444.67 08:28:11 INFO - PROCESS | 19077 | Values: 382.7 392.6 392.9 393.8 418.0 14791.5 17142.7 17659.3 19095.3 19102.2 18281.5 18566.4 18961.0 19069.4 19085.3 5510.8 5653.1 5658.3 5672.6 5695.1 4110.1 5554.2 5606.2 5884.8 6173.2 6190.5 6243.3 6250.7 6261.8 6270.2 5899.0 6252.7 6304.9 6308.5 6308.6 4828.7 5521.0 5547.4 5553.8 5561.9 13364.2 13572.8 13846.0 13873.4 13876.1 2266.4 2315.8 2322.0 2560.5 2991.9 9279.0 9691.9 9725.7 9875.3 10386.3 1790.9 1882.7 1907.2 1937.0 2058.0 74436.0 84177.6 84868.0 86969.6 88823.6 10325.3 10779.9 11129.6 11173.6 11346.4 15390.0 15455.7 15460.3 15518.5 15537.1 8359.6 8628.0 8677.0 8732.0 8798.6 8356.2 8426.0 8508.6 8524.4 8649.0 4622.5 4656.0 4680.7 4732.8 4808.1 1833.7 1883.8 1968.4 1993.5 2004.6 957.8 1041.6 1084.1 1125.8 1135.5 17580.6 18554.4 19118.2 19299.0 19469.8 1013.9 1027.6 1029.4 1077.2 1082.3 10377.7 10858.4 10885.6 10931.8 10945.3 509715.0 519619.0 520300.0 533404.0 538212.0 63958.7 77555.2 81213.6 87308.0 100388.0 1065.9 1066.2 1104.5 1108.1 1109.9 17450.8 17585.0 17861.3 17944.6 17957.4 41233.0 41341.4 42691.8 43869.3 44348.6 59523.4 59804.7 59853.0 60656.7 61949.0 7552.8 7755.9 8020.4 8550.4 8781.6 9808.4 13601.5 13822.3 13916.4 13952.8 11967.6 12006.5 12062.6 12195.5 12271.4 11382.4 11815.5 11965.2 11979.1 12089.4 12219.8 12245.6 12402.3 12428.3 12578.3 7741.9 8214.5 8238.7 8246.4 8450.7 10386.4 10762.7 11113.6 11379.1 11652.8 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | [#3] mootools.html Cycles:180 Average:5435.34 Median:2741.95 stddev:3008.45 (109.7%) stddev-sans-first:3001.04 08:28:11 INFO - PROCESS | 19077 | Values: 1325.9 1471.9 1523.1 1628.6 1788.6 7684.3 8036.5 8105.0 8263.6 8313.8 7820.8 8003.2 8007.3 8010.8 8026.8 2247.9 2286.8 2289.1 2291.7 2295.9 2247.5 2260.4 2260.5 2263.2 2286.5 2252.2 2263.3 2267.2 2276.1 2276.2 2246.5 2251.0 2252.2 2255.4 2256.6 2234.8 2241.7 2242.6 2245.1 2246.3 7809.7 8050.4 8112.7 8143.3 8180.8 4926.9 5022.6 5026.3 5048.5 5052.6 3250.4 3274.9 3330.4 3388.8 3442.4 1118.8 1144.8 1157.2 1161.7 1167.8 10987.0 10997.4 11039.8 11084.1 11142.4 4222.9 4263.5 4313.2 4349.5 4360.8 9059.2 9074.8 9080.7 9131.8 9131.9 4987.8 4988.4 5016.3 5021.2 5039.3 4086.8 4537.9 4593.9 4727.0 4760.3 3921.4 3944.7 4014.8 4018.7 4046.4 1288.6 1313.5 1319.3 1321.2 1339.3 788.7 941.4 942.5 942.6 980.1 5417.2 5607.5 5664.9 5761.4 5777.2 482.8 492.3 496.2 504.2 554.4 3783.6 3913.1 3992.5 4026.3 4029.2 9712.5 10097.0 10141.0 10141.2 10153.3 9962.5 9972.5 10070.6 10101.2 10111.8 7310.8 7630.2 7684.5 7778.3 7831.0 5800.6 5812.5 5897.2 5926.6 5946.4 9054.3 9304.2 9528.6 9631.8 9800.2 9394.7 9566.0 9684.9 9899.4 9914.8 6579.3 6952.4 7225.6 7453.4 7603.8 8026.0 8056.9 8088.1 8153.5 8161.1 7196.1 7266.0 7378.6 7390.6 7477.1 7019.2 7181.7 7267.1 7368.7 7414.7 6706.2 6723.4 6727.1 6729.1 6738.0 4638.5 4720.1 4721.9 4739.3 4830.7 4768.3 4784.9 4785.9 4808.4 4838.5 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | [#4] prototype.html Cycles:180 Average:4607.07 Median:5263.88 stddev:1337.01 (25.4%) stddev-sans-first:1301.65 08:28:11 INFO - PROCESS | 19077 | Values: 330.5 332.4 335.2 337.9 345.9 5085.6 5225.3 5287.8 5321.1 5338.1 5243.9 5261.1 5266.7 5276.9 5312.5 4135.4 4135.8 4139.2 4140.3 4144.5 3946.6 3980.0 4098.7 4118.6 4133.3 4077.4 4081.7 4083.4 4120.2 4124.5 4067.6 4109.2 4124.0 4129.4 4160.7 3464.8 3706.6 3707.4 3708.4 3717.3 5044.4 5050.4 5058.0 5085.8 5090.1 4686.4 4690.1 4716.9 4717.5 4740.8 5368.0 5397.9 5501.5 5505.7 5528.0 3962.8 3982.7 3984.7 3986.0 3992.7 5707.3 5810.3 5815.6 5820.4 5834.2 3971.1 4067.1 4067.9 4074.4 4077.0 4093.7 4097.6 4098.0 4102.8 4105.5 4662.9 4671.7 4675.4 4700.4 4720.6 5570.0 5585.2 5613.4 5641.5 5644.2 1390.9 1421.3 1421.5 1426.5 1426.8 988.6 994.5 1013.3 1013.6 1015.3 4985.8 4991.7 5009.0 5059.7 5077.5 5349.1 5363.7 5365.5 5384.2 5394.6 4990.6 5017.2 5058.4 5067.3 5349.3 2926.3 2930.9 2932.4 2935.4 2973.7 5682.9 5711.6 5755.5 5771.6 5777.6 5475.9 5622.7 5629.6 5647.4 5662.7 5578.2 5694.0 5717.7 5788.5 5813.6 5335.9 5389.8 5450.8 5490.2 5620.7 5487.3 5495.3 5510.5 5514.6 5539.2 5668.3 5695.0 5731.7 5809.4 5814.4 3669.4 3862.2 3909.2 4018.5 4032.3 5624.2 5722.5 5722.7 5730.0 5737.7 5532.5 5535.4 5559.2 5569.8 5580.7 5395.8 5482.8 5498.7 5505.4 5520.4 5278.8 5298.7 5331.5 5339.0 5349.7 5276.9 5517.0 5520.3 5540.3 5561.3 5359.8 5359.8 5381.2 5381.7 5436.2 08:28:11 INFO - PROCESS | 19077 | 08:28:11 INFO - PROCESS | 19077 | [#5] yui.html Cycles:180 Average:15003.94 Median:4623.48 stddev:17164.32 (371.2%) stddev-sans-first:17179.28 08:28:11 INFO - PROCESS | 19077 | Values: 788.8 793.8 821.8 838.4 853.4 14048.9 15143.8 15284.5 15370.3 15383.8 12913.1 13931.9 14272.2 14278.6 14333.6 813.2 816.3 828.5 832.0 834.3 824.6 829.5 829.8 830.1 831.0 867.6 871.7 879.9 880.0 881.6 867.1 870.2 874.3 874.4 875.1 636.1 636.5 637.8 638.9 639.1 13081.2 13582.7 13593.8 13602.8 13632.7 12486.3 12524.8 12540.4 12554.0 12624.6 10780.3 10780.3 10781.7 10793.1 11152.8 3755.6 3803.8 3845.7 4348.1 4467.4 44388.0 53006.0 54331.7 54340.7 55178.4 10848.6 11041.2 11043.4 11145.8 11411.0 19043.4 19127.6 19149.1 19164.3 19199.0 8505.5 8560.2 8815.3 8843.4 8858.2 4812.7 5014.8 5026.5 5175.2 5175.7 3379.0 3439.9 3461.3 3475.5 3476.7 643.1 646.2 649.7 661.8 667.0 672.3 678.2 712.3 715.4 727.3 9877.2 9882.6 9929.2 9991.2 10047.7 665.3 693.7 699.4 700.5 723.3 4580.2 4666.8 4767.2 4771.5 4771.8 59853.0 63660.4 63743.4 63919.4 63958.0 55918.7 56401.7 57068.0 57171.0 58805.0 38041.4 46691.5 48830.0 53080.0 53606.5 9694.0 9743.1 9752.3 9926.0 10002.1 34456.8 35699.0 35946.3 35968.5 36037.6 41808.8 47930.3 48310.6 48429.5 48650.0 11906.5 12194.7 12242.4 12255.3 12465.5 13720.1 13928.4 13980.5 14016.6 14056.1 13123.6 13163.5 13164.1 13478.4 13510.9 13099.5 13218.2 13340.4 13351.7 13488.3 13140.8 13280.5 13298.7 13306.0 14036.9 10557.7 10558.2 10943.7 11297.2 11373.3 11036.1 11048.3 11051.2 11494.8 11509.1 08:28:11 INFO - PROCESS | 19077 | -------- Summary: end -------- 08:28:11 INFO - PROCESS | 19077 | 08:28:12 INFO - TEST-INFO | 19077: exit 0 08:28:12 INFO - TEST-OK | dromaeo_css | took 1112272ms 08:28:12 INFO - TEST-START | kraken 08:28:12 INFO - operating with platform_type : linux_ 08:28:12 INFO - Initialising browser for kraken test... 08:28:12 INFO - TEST-INFO | started process 19261 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp15xnno/profile http://localhost:52235/getInfo.html) 08:28:16 INFO - PROCESS | 19261 | __metrics Screen width/height:1600/1200 08:28:16 INFO - PROCESS | 19261 | colorDepth:24 08:28:16 INFO - PROCESS | 19261 | Browser inner width/height: 1024/697 08:28:16 INFO - PROCESS | 19261 | __metrics 08:28:17 ERROR - PROCESS | 19261 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:28:18 INFO - TEST-INFO | 19261: exit 0 08:28:18 INFO - Browser initialized. 08:28:18 INFO - Running cycle 1/1 for kraken test... 08:28:18 INFO - TEST-INFO | started process 19343 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp15xnno/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:30 INFO - PROCESS | 19343 | RSS: Main: 152559616 08:28:30 INFO - PROCESS | 19343 | 08:28:32 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52235/tests/kraken/driver.html?testName=audio-beat-detection) 08:28:33 INFO - PROCESS | 19343 | RSS: Main: 207724544 08:28:33 INFO - PROCESS | 19343 | 08:28:36 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52235/tests/kraken/driver.html?testName=audio-dft) 08:28:36 INFO - PROCESS | 19343 | RSS: Main: 197185536 08:28:36 INFO - PROCESS | 19343 | 08:28:40 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52235/tests/kraken/driver.html?testName=audio-fft) 08:28:40 INFO - PROCESS | 19343 | RSS: Main: 264839168 08:28:40 INFO - PROCESS | 19343 | 08:28:42 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52235/tests/kraken/driver.html?testName=audio-oscillator) 08:28:43 INFO - PROCESS | 19343 | RSS: Main: 174366720 08:28:43 INFO - PROCESS | 19343 | 08:28:45 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:28:46 INFO - PROCESS | 19343 | RSS: Main: 177938432 08:28:46 INFO - PROCESS | 19343 | 08:28:49 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52235/tests/kraken/driver.html?testName=imaging-darkroom) 08:28:49 INFO - PROCESS | 19343 | RSS: Main: 213790720 08:28:49 INFO - PROCESS | 19343 | 08:28:53 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52235/tests/kraken/driver.html?testName=imaging-desaturate) 08:28:53 INFO - PROCESS | 19343 | RSS: Main: 214892544 08:28:53 INFO - PROCESS | 19343 | 08:28:56 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52235/tests/kraken/driver.html?testName=json-parse-financial) 08:28:57 INFO - PROCESS | 19343 | RSS: Main: 215638016 08:28:57 INFO - PROCESS | 19343 | 08:28:58 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:28:59 INFO - PROCESS | 19343 | RSS: Main: 201355264 08:28:59 INFO - PROCESS | 19343 | 08:29:00 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:29:01 INFO - PROCESS | 19343 | RSS: Main: 205103104 08:29:01 INFO - PROCESS | 19343 | 08:29:03 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:29:04 INFO - PROCESS | 19343 | RSS: Main: 215068672 08:29:04 INFO - PROCESS | 19343 | 08:29:06 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:29:07 INFO - PROCESS | 19343 | RSS: Main: 223887360 08:29:07 INFO - PROCESS | 19343 | 08:29:10 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:29:11 INFO - PROCESS | 19343 | RSS: Main: 221122560 08:29:11 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | Cycle 1(1): loaded http://localhost:52235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52235/tests/kraken/driver.html?testName=ai-astar) 08:29:12 INFO - PROCESS | 19343 | RSS: Main: 266571776 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | __start_tp_report 08:29:12 INFO - PROCESS | 19343 | _x_x_mozilla_page_load 08:29:12 INFO - PROCESS | 19343 | _x_x_mozilla_page_load_details 08:29:12 INFO - PROCESS | 19343 | |i|pagename|runs| 08:29:12 INFO - PROCESS | 19343 | |0;ai-astar;112;105;110;111;114;110;110;111;111;112 08:29:12 INFO - PROCESS | 19343 | |1;audio-beat-detection;150;125;158;171;126;160;159;126;188;134 08:29:12 INFO - PROCESS | 19343 | |2;audio-dft;162;161;162;159;160;162;162;161;161;162 08:29:12 INFO - PROCESS | 19343 | |3;audio-fft;75;75;76;128;76;75;78;75;140;74 08:29:12 INFO - PROCESS | 19343 | |4;audio-oscillator;92;92;121;92;91;93;128;92;92;118 08:29:12 INFO - PROCESS | 19343 | |5;imaging-gaussian-blur;116;117;116;117;117;117;117;116;117;117 08:29:12 INFO - PROCESS | 19343 | |6;imaging-darkroom;145;146;146;148;145;145;147;147;146;145 08:29:12 INFO - PROCESS | 19343 | |7;imaging-desaturate;95;95;96;95;95;95;94;95;95;95 08:29:12 INFO - PROCESS | 19343 | |8;json-parse-financial;58;57;63;56;57;56;56;76;55;55 08:29:12 INFO - PROCESS | 19343 | |9;json-stringify-tinderbox;58;59;59;59;58;59;58;58;58;58 08:29:12 INFO - PROCESS | 19343 | |10;stanford-crypto-aes;80;85;84;78;84;81;83;78;82;79 08:29:12 INFO - PROCESS | 19343 | |11;stanford-crypto-ccm;134;125;129;125;124;122;126;136;172;121 08:29:12 INFO - PROCESS | 19343 | |12;stanford-crypto-pbkdf2;175;171;175;168;172;168;178;169;171;167 08:29:12 INFO - PROCESS | 19343 | |13;stanford-crypto-sha256-iterative;64;65;71;71;64;63;64;65;63;62 08:29:12 INFO - PROCESS | 19343 | __end_tp_report 08:29:12 INFO - PROCESS | 19343 | __start_cc_report 08:29:12 INFO - PROCESS | 19343 | _x_x_mozilla_cycle_collect,1614 08:29:12 INFO - PROCESS | 19343 | __end_cc_report 08:29:12 INFO - PROCESS | 19343 | __startTimestamp1462548552950__endTimestamp 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | ------- Summary: start ------- 08:29:12 INFO - PROCESS | 19343 | Number of tests: 14 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.32 (2.1%) stddev-sans-first:2.40 08:29:12 INFO - PROCESS | 19343 | Values: 112.0 105.0 110.0 111.0 114.0 110.0 110.0 111.0 111.0 112.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:158.50 stddev:21.50 (13.6%) stddev-sans-first:22.81 08:29:12 INFO - PROCESS | 19343 | Values: 150.0 125.0 158.0 171.0 126.0 160.0 159.0 126.0 188.0 134.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#2] audio-dft Cycles:10 Average:161.20 Median:162.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 08:29:12 INFO - PROCESS | 19343 | Values: 162.0 161.0 162.0 159.0 160.0 162.0 162.0 161.0 161.0 162.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#3] audio-fft Cycles:10 Average:87.20 Median:75.00 stddev:24.85 (33.1%) stddev-sans-first:25.96 08:29:12 INFO - PROCESS | 19343 | Values: 75.0 75.0 76.0 128.0 76.0 75.0 78.0 75.0 140.0 74.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#4] audio-oscillator Cycles:10 Average:101.10 Median:92.00 stddev:14.86 (16.2%) stddev-sans-first:15.39 08:29:12 INFO - PROCESS | 19343 | Values: 92.0 92.0 121.0 92.0 91.0 93.0 128.0 92.0 92.0 118.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 08:29:12 INFO - PROCESS | 19343 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 08:29:12 INFO - PROCESS | 19343 | Values: 145.0 146.0 146.0 148.0 145.0 145.0 147.0 147.0 146.0 145.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 08:29:12 INFO - PROCESS | 19343 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:57.00 stddev:6.44 (11.3%) stddev-sans-first:6.82 08:29:12 INFO - PROCESS | 19343 | Values: 58.0 57.0 63.0 56.0 57.0 56.0 56.0 76.0 55.0 55.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 08:29:12 INFO - PROCESS | 19343 | Values: 58.0 59.0 59.0 59.0 58.0 59.0 58.0 58.0 58.0 58.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.50 stddev:2.59 (3.1%) stddev-sans-first:2.70 08:29:12 INFO - PROCESS | 19343 | Values: 80.0 85.0 84.0 78.0 84.0 81.0 83.0 78.0 82.0 79.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#11] stanford-crypto-ccm Cycles:10 Average:131.40 Median:127.50 stddev:15.07 (11.8%) stddev-sans-first:15.96 08:29:12 INFO - PROCESS | 19343 | Values: 134.0 125.0 129.0 125.0 124.0 122.0 126.0 136.0 172.0 121.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:171.50 stddev:3.63 (2.1%) stddev-sans-first:3.61 08:29:12 INFO - PROCESS | 19343 | Values: 175.0 171.0 175.0 168.0 172.0 168.0 178.0 169.0 171.0 167.0 08:29:12 INFO - PROCESS | 19343 | 08:29:12 INFO - PROCESS | 19343 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:64.50 stddev:3.19 (4.9%) stddev-sans-first:3.35 08:29:12 INFO - PROCESS | 19343 | Values: 64.0 65.0 71.0 71.0 64.0 63.0 64.0 65.0 63.0 62.0 08:29:12 INFO - PROCESS | 19343 | -------- Summary: end -------- 08:29:12 INFO - PROCESS | 19343 | 08:29:13 INFO - TEST-INFO | 19343: exit 0 08:29:13 INFO - TEST-OK | kraken | took 61512ms 08:29:13 INFO - SUITE-END | took 1173s 08:29:14 INFO - Completed test suite (00:19:33) 08:29:14 INFO - javascript benchmark 08:29:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3132.5927629408175, 3142.8585713642883, 3146.1190866557363, 3180.1890517917927, 3195.55262263283, 5302.0, 5334.279872217249, 5360.678359298445, 5443.237946687462, 5464.754086066127, 5383.0, 5389.461053894609, 5395.379531353896, 5424.945750542493, 5469.453054694529, 4829.323894654743, 4842.12841688496, 5052.065367906949, 5089.643724939256, 5101.285819985205, 5021.673591216578, 5023.623228257877, 5066.721330326849, 5080.263361812535, 5087.643864929438, 4736.502667219944, 4855.295982082596, 4892.4989010110785, 4903.730294833783, 4919.532644398776, 4871.098846713369, 4889.48660390661, 4948.0846043481715, 4961.751912404401, 5007.54932056113, 4878.341423907763, 4924.975375123101, 4951.752412379403, 4959.975200124012, 4987.977464619744, 5198.012377648235, 5289.894202115975, 5316.6544174628525, 5340.813071542516, 5390.1914712793005, 5793.8841223175305, 5832.708364581797, 5853.9414605854245, 5859.091840764646, 5881.500072493843, 5632.295963004624, 5732.3121225453215, 5855.677937713466, 5869.9706501467645, 5932.466078052996, 5327.600429967729, 5401.594880383947, 5446.319210098738, 5463.562914966793, 5481.095619222863, 5923.289205295306, 5957.523398128139, 5960.225170727865, 5979.133025711248, 5979.372165922516, 5554.361248456479, 5627.549796016309, 5672.404397538282, 5732.283464566929, 5798.072308430631, 5787.363390027093, 5787.768489260467, 5791.305043394819, 5800.970995144998, 5829.183914252008, 5762.1068734346245, 5766.855828604234, 5773.624714393551, 5776.68228247442, 5780.335261444903, 5630.887382252332, 5738.512226460798, 5744.913826292609, 5755.107958266475, 5778.451047150514, 4355.60799528044, 4357.455318085239, 4363.890902727394, 4380.759058251763, 4406.074724307867, 3897.240038192582, 3904.39481880309, 3927.0378757204667, 3952.940705889414, 3962.603739626072, 5281.498257665516, 5287.471252874681, 5398.892022159614, 5419.078756611386, 5441.129419292894, 5835.912461313083, 5849.5027922626205, 5851.590388672837, 5853.209816674779, 5875.529957603381, 5261.763220655003, 5467.425920278393, 5483.204935284361, 5494.642848214933, 5495.120780675153, 4942.036302920896, 4966.925496117561, 4972.925406118911, 4987.0, 4987.825426110141, 5983.940160598426, 6011.158437818709, 6014.669193194416, 6019.097135429633, 6031.547633927473, 5617.297837770278, 5748.367679555329, 5844.620099693507, 5852.502537284208, 5857.0, 5925.9407405925385, 5995.2206213192, 5998.0, 6018.0, 6038.9396106038375, 5679.9432005681065, 5700.629459085146, 5703.942960570341, 5706.400827913009, 5713.285839270091, 5685.175649530712, 5802.0, 5813.82558523245, 5821.854453638693, 5890.911636325544, 5677.205191273142, 5680.147977803363, 5700.230468886647, 5720.771169153188, 5729.341125770423, 5607.8878422432135, 5657.151427285776, 5671.8865622688145, 5673.943260567342, 5685.829425117253, 5327.067763141357, 5473.343198816167, 5502.119660854244, 5513.255710479034, 5516.365617953987, 5769.711514424346, 5802.071668533014, 5818.6217895836635, 5819.76720931158, 5829.329627092939, 5581.218629391808, 5616.3260408749575, 5625.409332020243, 5650.23721797552, 5663.9716801415725], "value": 5338.366675897318, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.4311988930681, 641.1024565608151, 642.9132067170932, 643.202428988055, 657.3393739292012, 31122.37755244894, 34153.65846341539, 41880.0, 42595.57404425955, 42761.14477710444, 43735.0, 44016.55983440165, 44176.558234417644, 44198.55801441985, 44262.0, 8754.3871928965, 8912.64349426022, 8918.866217006751, 8929.910700893006, 8933.062028487013, 8785.516796576187, 8826.823463530725, 8904.910950890506, 8905.554722263892, 8913.59888805002, 8885.644574217024, 8937.865932010993, 8938.64245430182, 8944.776380590505, 8948.463092214455, 8863.0, 8902.287816974627, 8918.241949434307, 8918.955405222998, 8922.420042697204, 8800.07599202081, 8825.205731484197, 8886.555672216427, 8902.821943561157, 8905.465672059632, 41204.79397602993, 41557.376639350434, 41572.37641435381, 41606.79196604028, 41622.58377416248, 3770.20825626616, 3794.0135564753086, 3814.6757525610356, 3866.5940076291868, 4112.979435102835, 31774.0, 32060.519092213635, 32292.35415291681, 32626.0, 32743.6725632743, 4319.740815551046, 4434.1131773645075, 4436.866893993185, 4456.844010459618, 4482.798274077674, 310641.0, 311143.0, 311914.0, 312853.0, 313304.0, 55244.72377638086, 55684.72157639267, 56038.71980640071, 56074.0, 56131.158032629544, 36562.35469403883, 37449.62550374462, 37459.62540374616, 39089.609103908595, 39091.21817563633, 17538.210780514906, 17771.378001769877, 17803.643927121386, 17815.376461823773, 17826.46520604384, 13544.729105417837, 13654.0, 13664.658383540293, 13795.448182072605, 13800.102993305403, 19304.486859198616, 19507.122179501956, 19582.902085489768, 19639.214431422584, 19651.213951442067, 7322.487425880244, 7403.51877127985, 7505.324520793155, 7521.022267105242, 7522.285382888617, 1033.7725700345911, 1046.7644779924456, 1081.2161183142316, 1149.3850789827402, 1189.3339729751367, 31850.2037449061, 32228.777122287585, 32615.18462038468, 33107.0, 33268.50097248543, 1126.1047467263545, 1174.0548858169257, 1177.464253764535, 1193.838831757719, 1239.293602646483, 17250.309987600605, 17426.0, 17474.388396406066, 17502.299908003537, 17555.912220438815, 522490.0, 529639.0, 529977.0, 534100.0, 534996.0, 57543.424565753805, 58029.709851450476, 58040.0, 58124.0, 58138.0, 1301.9609411717474, 1389.3400634698437, 1394.6443656867636, 1408.9929550352183, 1418.5389748331834, 32046.6795332053, 33617.32765344631, 33817.83091084529, 34069.318613628086, 34189.0, 49345.753271233414, 49977.25034124418, 49989.0, 50006.74996624994, 50013.24980125374, 347136.0, 347663.0, 351005.0, 351030.0, 351386.0, 23286.76713232846, 26103.477930440906, 26133.607995879698, 27456.45087098287, 29831.701682982894, 45309.32036019397, 46512.767436162605, 47245.76377118092, 47354.52645473501, 47482.575522732994, 40018.199636007696, 40083.0, 40291.1941761169, 40607.796961015, 41149.588504114574, 39012.80493597514, 39070.60929390784, 39584.40623390594, 39653.603463966145, 39816.20367592574, 40825.795871020455, 42988.78505607452, 43589.34615980699, 43634.56365436305, 45144.0, 34916.82541587378, 37224.0, 37323.06692332713, 37606.0, 37747.43378849264, 28057.859710701316, 28223.57664634991, 28409.857950710113, 28675.713242867303, 29016.854915725286], "value": 19862.033742723146, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.678550017985, 392.6113147983497, 392.9115948911493, 393.807034553069, 418.0343406730453, 14791.482298119568, 17142.657146857055, 17659.29362825487, 19095.331663391786, 19102.235910563577, 18281.451556453296, 18566.35017774378, 18961.0, 19069.427917162473, 19085.332013379535, 5510.8071217507295, 5653.095504719246, 5658.34928983166, 5672.581854536366, 5695.060315048033, 4110.136871257025, 5554.1946417769395, 5606.21512988182, 5884.794032208873, 6173.228346456693, 6190.473809726179, 6243.2508099027955, 6250.687465626723, 6261.812145635638, 6270.247570291572, 5898.970505147489, 6252.718627661743, 6304.936950630481, 6308.463780578656, 6308.558400911938, 4828.710277383333, 5520.972395138038, 5547.44525547446, 5553.7778488860395, 5561.916571251434, 13364.198148111145, 13572.796408053888, 13846.0, 13873.4450621975, 13876.098053626481, 2266.444721043355, 2315.8263130265295, 2321.9535609287886, 2560.46230291639, 2991.9401611967637, 9279.0, 9691.854622180674, 9725.65960191397, 9875.259355548362, 10386.324888882198, 1790.9462716118537, 1882.6799444094536, 1907.2085084689836, 1937.0, 2058.043010000357, 74436.0, 84177.57911210466, 84868.0, 86969.56515217447, 88823.55588222083, 10325.32885362449, 10779.946100269448, 11129.610463633733, 11173.553057877594, 11346.3759493227, 15390.0, 15455.690886182214, 15460.304286307142, 15518.456854010055, 15537.145456999973, 8359.58202089893, 8627.95686021566, 8677.045524992245, 8732.0, 8798.560071996375, 8356.164383561596, 8426.0, 8508.617112229964, 8524.389883444821, 8648.956755216184, 4622.491525932145, 4655.976720116378, 4680.672352935262, 4732.763361831895, 4808.08646357191, 1833.7066069428827, 1883.7771429712795, 1968.4389948864505, 1993.5414854583528, 2004.5690176611806, 957.8275910336067, 1041.5833666533297, 1084.1110289562482, 1125.8423820665114, 1135.5230803062568, 17580.560485987953, 18554.44336669902, 19118.2352705893, 19299.0, 19469.8053019468, 1013.8884722680499, 1027.5992362978445, 1029.361795686664, 1077.2459278505078, 1082.3073233130804, 10377.740556486147, 10858.402787846586, 10885.564577416973, 10931.781364372828, 10945.343279403261, 509715.0, 519619.0, 520300.0, 533404.0, 538212.0, 63958.68020659867, 77555.22444775593, 81213.59393203114, 87308.0, 100388.0, 1065.9040686338267, 1066.2323127348297, 1104.469854469859, 1108.0747575774158, 1109.8890110988998, 17450.82549174492, 17585.0, 17861.28554857791, 17944.641107178046, 17957.371491997717, 41233.0, 41341.37987930243, 42691.78654106709, 43869.341959871264, 44348.556514434444, 59523.40476595179, 59804.70097649484, 59853.0, 60656.69671651613, 61949.0, 7552.811179720551, 7755.883661745192, 8020.398470114648, 8550.444221125606, 8781.560921954004, 9808.362456440309, 13601.455941762219, 13822.30888455553, 13916.443342266197, 13952.79070813959, 11967.64097077089, 12006.519739210684, 12062.638120856389, 12195.451204695988, 12271.447784849524, 11382.430878456209, 11815.468303926516, 11965.162438629213, 11979.101567382308, 12089.39553022363, 12219.816702749644, 12245.57140500114, 12402.25586464815, 12428.316442595744, 12578.30819304947, 7741.92258077412, 8214.4660597061, 8238.670453181805, 8246.38152138604, 8450.746477605682, 10386.42874641902, 10762.677119686421, 11113.555457781598, 11379.146564007568, 11652.766944661229], "value": 9161.587575806987, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.86888740574, 1471.8601732835384, 1523.0785374848217, 1628.633557449574, 1788.6243888783347, 7684.308412242897, 8036.517808931469, 8105.027396712403, 8263.586820658958, 8313.833723325544, 7820.8044798880055, 8003.239692229244, 8007.279344858976, 8010.799730006753, 8026.799330016753, 2247.8538894971775, 2286.7598902115205, 2289.072925465189, 2291.747907730148, 2295.8737269450176, 2247.505548779266, 2260.446190683285, 2260.5026894083276, 2263.162629826966, 2286.451251699597, 2252.166698321615, 2263.2531264682616, 2267.206477732797, 2276.078188333719, 2276.203328834903, 2246.4945387287894, 2250.954980900381, 2252.2117258959397, 2255.3684968208845, 2256.627656436686, 2234.821214302868, 2241.7085778848646, 2242.55148970205, 2245.1468441991938, 2246.3148739634403, 7809.726659566888, 8050.355971522265, 8112.716054938049, 8143.267105960433, 8180.7954801130445, 4926.852194434173, 5022.573081288095, 5026.346574945271, 5048.5203905628905, 5052.621053420971, 3250.398676244902, 3274.9017529474154, 3330.383878982396, 3388.762786604939, 3442.397580423416, 1118.7930232906856, 1144.7710457908452, 1157.2477889371892, 1161.680537852089, 1167.830664553635, 10987.0, 10997.395143267117, 11039.779204415947, 11084.057855082328, 11142.387168705718, 4222.894427639334, 4263.509696384894, 4313.180495705806, 4349.499807522176, 4360.847370342023, 9059.229965452878, 9074.820273364421, 9080.727578172533, 9131.77170570741, 9131.863022054675, 4987.750612469362, 4988.376452943382, 5016.322796422436, 5021.171506701354, 5039.3448851649055, 4086.7956602169775, 4537.886552836139, 4593.908121837611, 4726.95273047272, 4760.262159365304, 3921.4117882317883, 3944.6647035001774, 4014.7992600369867, 4018.678505719535, 4046.433499310099, 1288.6391810293132, 1313.4680454415977, 1319.3403298350825, 1321.2270821569298, 1339.2567125245387, 788.6727008291609, 941.3645789092335, 942.533445944262, 942.604106275366, 980.1423754215061, 5417.214503896913, 5607.467290607386, 5664.943350566524, 5761.366249712528, 5777.248957635565, 482.80205115903016, 492.278811541094, 496.1590104772448, 504.17567277500814, 554.3763266325384, 3783.564890037626, 3913.0608653922855, 3992.5208974923194, 4026.295398305285, 4029.17401932609, 9712.465814380277, 10097.0, 10141.0, 10141.2394070445, 10153.340032897839, 9962.452065136487, 9972.501374931224, 10070.597176112873, 10101.19190464761, 10111.775409409918, 7310.780676579711, 7630.236976302325, 7684.500507467109, 7778.299953004257, 7830.960845195737, 5800.622959507618, 5812.5349972001295, 5897.233359663216, 5926.555508336805, 5946.405359464192, 9054.320925930713, 9304.16262536375, 9528.618855245713, 9631.807363852544, 9800.215982721507, 9394.67118650844, 9566.043395660377, 9684.854727178958, 9899.406035637885, 9914.752131196778, 6579.309172537006, 6952.374286314256, 7225.5664660118455, 7453.440991925519, 7603.809904752426, 8025.9598702006115, 8056.879146812919, 8088.069871964564, 8153.470024448629, 8161.061477929877, 7196.136463624399, 7266.0, 7378.631068446663, 7390.593517356597, 7477.140128885248, 7019.227884932551, 7181.676824542804, 7267.091613548307, 7368.705251789868, 7414.703411863465, 6706.195256569243, 6723.361280678328, 6727.091842601186, 6729.0915726376315, 6738.022986666808, 4638.512956139692, 4720.103180395851, 4721.881952951204, 4739.312799643963, 4830.661853670209, 4768.308595253599, 4784.880377990439, 4785.880352991203, 4808.374911261513, 4838.516148385274], "value": 4349.549108603439, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.5256956267756, 332.380111092812, 335.15875153365056, 337.9357921994821, 345.87029863801075, 5085.567726743231, 5225.294585230993, 5287.788488460457, 5321.1220148675475, 5338.119210330296, 5243.947560524393, 5261.105612045952, 5266.657667251636, 5276.947230527693, 5312.4687531246855, 4135.379693046037, 4135.834566617346, 4139.172165566891, 4140.3168477201225, 4144.502659680843, 3946.605339466045, 3979.9652090456543, 4098.6516146127615, 4118.629323360897, 4133.276676581601, 4077.408775727518, 4081.7346872453345, 4083.448734420845, 4120.196561670476, 4124.463819703449, 4067.5525692173837, 4109.178164367138, 4123.9792501674265, 4129.421880936671, 4160.687948403882, 3464.8440820163155, 3706.5552133743854, 3707.35121353762, 3708.3695771718603, 3717.2565486902727, 5044.39467263927, 5050.39395272566, 5058.0, 5085.847424577269, 5090.134677104901, 4686.414198225222, 4690.0854333405, 4716.9292460613115, 4717.528247175289, 4740.786664600101, 5367.953249116424, 5397.919031214535, 5501.504864562168, 5505.6696598203835, 5527.972360138214, 3962.7622342659533, 3982.721209515335, 3984.721069525134, 3985.9800700996598, 3992.7205095643317, 5707.343655479632, 5810.33181184165, 5815.563832712563, 5820.417958204188, 5834.154047663065, 3971.126352202511, 4067.1255680028935, 4067.901666550015, 4074.4295798588223, 4076.9592304077173, 4093.6520395766693, 4097.610726980932, 4098.0, 4102.774347410861, 4105.527864295585, 4662.930056049094, 4671.696339737974, 4675.4155730533685, 4700.435947686299, 4720.598749106296, 5569.972150139304, 5585.1901474286005, 5613.410591887875, 5641.52047075995, 5644.238027866268, 1390.9289846817894, 1421.3461807568635, 1421.5451055662088, 1426.4793350427226, 1426.8216472940883, 988.5798535622332, 994.5226291380178, 1013.2906965124442, 1013.6046941692746, 1015.2690063154517, 4985.8005679772405, 4991.675541089818, 5009.0, 5059.671121377099, 5077.492250774893, 5349.117395629678, 5363.693611746909, 5365.517103460629, 5384.246205531229, 5394.622376433612, 4990.6257030722845, 5017.1721665924715, 5058.4435712071645, 5067.290579318898, 5349.304590403301, 2926.341573146025, 2930.8827646893888, 2932.3548819259727, 2935.442265969459, 2973.6728959814404, 5682.914756278659, 5711.6292089898125, 5755.510781583613, 5771.5571107223195, 5777.555611097226, 5475.945240547623, 5622.690752008597, 5629.634073785191, 5647.37878833328, 5662.66024038559, 5578.219049333096, 5694.0, 5717.656940583578, 5788.45009724084, 5813.593048486735, 5335.918991593359, 5389.844062375081, 5450.754716037867, 5490.176473529003, 5620.747066382104, 5487.341519017744, 5495.2856128703015, 5510.504054635102, 5514.61397702157, 5539.19681646151, 5668.291463567054, 5695.0, 5731.7420716067045, 5809.419058094157, 5814.389489103583, 3669.3578623740204, 3862.1503269280715, 3909.237698648736, 4018.477597912253, 4032.2540330039037, 5624.240727501735, 5722.513586345207, 5722.713864306685, 5729.994501924293, 5737.684427356536, 5532.474414930575, 5535.446455354593, 5559.16612508127, 5569.777208911598, 5580.665160090408, 5395.78416863321, 5482.753276102505, 5498.725063746877, 5505.366882808528, 5520.420355862577, 5278.76245568958, 5298.682079075268, 5331.5201631853315, 5339.0, 5349.742810439466, 5276.894462110659, 5517.0, 5520.337559492887, 5540.279763630702, 5561.304836895388, 5359.812406565751, 5359.839204823861, 5381.246625472359, 5381.744044852964, 5436.157395603688], "value": 4188.507941430395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.7791418402845, 793.8134538383482, 821.8315245374699, 838.4131108224244, 853.4111463090469, 14048.929755351222, 15143.772843407343, 15284.465043723472, 15370.308336124874, 15383.846161538395, 12913.096083274175, 13931.860681393184, 14272.215028173447, 14278.571642850706, 14333.641658958531, 813.1827513349106, 816.3020617372135, 828.5360198288978, 832.0306842528478, 834.3158609939821, 824.5794644731201, 829.4774292195908, 829.8008477965275, 830.0744669693279, 831.0, 867.6312567158964, 871.7123349294718, 879.8900137482815, 879.9692010779625, 881.6120906800987, 867.1155421470097, 870.1994165367877, 874.2743522876029, 874.4316194473579, 875.1336177184605, 636.0522820996682, 636.4749082007316, 637.7895294552786, 638.9297177310492, 639.1212083385345, 13081.215127092404, 13582.660433489144, 13593.796093058612, 13602.795958060637, 13632.727345453035, 12486.313252771093, 12524.81212781809, 12540.435680394405, 12554.0, 12624.55814046504, 10780.29928054674, 10780.29928054674, 10781.730456738565, 10793.136549076133, 11152.832707509393, 3755.6244375562496, 3803.7527560708463, 3845.65389114981, 4348.086901750637, 4467.374567560544, 44388.0, 53006.0, 54331.72834135844, 54340.72829635866, 55178.44821551813, 10848.620298289521, 11041.227114102096, 11043.447827608587, 11145.832812507819, 11411.030062444619, 19043.42869713911, 19127.617447651246, 19149.138288777038, 19164.329248476515, 19199.0, 8505.532195729294, 8560.186782255676, 8815.25070369013, 8843.425177363451, 8858.247049000907, 4812.663113582085, 5014.84955451337, 5026.497350265017, 5175.171972484384, 5175.715335656575, 3378.9831050844587, 3439.8968030959113, 3461.273132642123, 3475.461303497962, 3476.7392445566684, 643.1317721076603, 646.1696719715237, 649.6849028221309, 661.8014595621295, 666.9899951500731, 672.3209558346106, 678.2064983968768, 712.3482013957274, 715.4169351978121, 727.31268950842, 9877.16044136242, 9882.604695812088, 9929.156021738088, 9991.150752186146, 10047.698569042941, 665.3180489997791, 693.729445516249, 699.433458898294, 700.5131433653562, 723.3164659396924, 4580.152671755736, 4666.8133274668635, 4767.165745994506, 4771.498992605726, 4771.832985845548, 59853.0, 63660.36339636544, 63743.36256637374, 63919.44322227152, 63958.0, 55918.720406397704, 56401.71799140978, 57068.0, 57171.0, 58805.0, 38041.42937855935, 46691.5330846694, 48830.0, 53080.0, 53606.463935360924, 9693.951530242304, 9743.123118919331, 9752.268579856682, 9926.0, 10002.149817265614, 34456.82771586126, 35699.0, 35946.28107437785, 35968.460473092404, 36037.639623604475, 41808.79095604503, 47930.281045785036, 48310.55068347815, 48429.5157048425, 48650.0, 11906.52373905034, 12194.695132621755, 12242.449089791158, 12255.264684118982, 12465.504139503317, 13720.108192967426, 13928.373223204777, 13980.510682126076, 14016.649583760487, 14056.086354386933, 13123.60629181126, 13163.53927612529, 13164.078514504276, 13478.393472293574, 13510.93244533771, 13099.541516046893, 13218.206907585576, 13340.399682014138, 13351.666208344868, 13488.32558372097, 13140.80288795688, 13280.468781248643, 13298.734025319245, 13306.0, 14036.929815350857, 10557.683269501927, 10558.155347572329, 10943.726406839574, 11297.209195356247, 11373.31760094397, 11036.075440008564, 11048.281861678965, 11051.226414150933, 11494.827577586175, 11509.13681473876], "value": 6893.120183548513, "unit": "score"}], "name": "dromaeo_css", "value": 7042.522422850771, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 110.0, 111.0, 114.0, 110.0, 110.0, 111.0, 111.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 125.0, 158.0, 171.0, 126.0, 160.0, 159.0, 126.0, 188.0, 134.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 159.0, 160.0, 162.0, 162.0, 161.0, 161.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 128.0, 76.0, 75.0, 78.0, 75.0, 140.0, 74.0], "value": 87.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 121.0, 92.0, 91.0, 93.0, 128.0, 92.0, 92.0, 118.0], "value": 101.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 148.0, 145.0, 145.0, 147.0, 147.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 63.0, 56.0, 57.0, 56.0, 56.0, 76.0, 55.0, 55.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0, 58.0, 58.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 85.0, 84.0, 78.0, 84.0, 81.0, 83.0, 78.0, 82.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 125.0, 129.0, 125.0, 124.0, 122.0, 126.0, 136.0, 172.0, 121.0], "value": 131.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 175.0, 168.0, 172.0, 168.0, 178.0, 169.0, 171.0, 167.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 71.0, 71.0, 64.0, 63.0, 64.0, 65.0, 63.0, 62.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1534.2000000000005, "alertThreshold": 2.0}]} 08:29:14 INFO - Return code: 0 08:29:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:29:14 ERROR - # TBPL SUCCESS # 08:29:14 INFO - Running post-action listener: _resource_record_post_action 08:29:14 INFO - ##### 08:29:14 INFO - ##### Finished run-tests step (success) 08:29:14 INFO - ##### 08:29:14 INFO - Running post-run listener: _resource_record_post_run 08:29:15 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 542408704; Read time: 8; Write time: 595076 08:29:15 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 251994112; Read time: 0; Write time: 336404 08:29:15 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290414592; Read time: 8; Write time: 258672 08:29:15 INFO - Running post-run listener: _upload_blobber_files 08:29:15 INFO - Blob upload gear active. 08:29:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:15 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 08:29:15 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'] 08:29:15 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 08:29:15 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:16 INFO - (blobuploader) - INFO - Done attempting. 08:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:17 INFO - (blobuploader) - INFO - Done attempting. 08:29:17 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:17 INFO - Return code: 0 08:29:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:29:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a9be0836f67325813b0e1e62ff5096aa65dd23ff0cbcff6948866e9a7f2d5da3f679ceee9fbe7e598e553ac80ab2407ea1c2832edf2a2439481374f8395dcd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91d24aaa676a86febc793363cec588adaec6c6efccb4db196e62cf91617abe8768e2793ba8ee9009de05dbdbcdeda591b920d454697ef6ca590aa3b66827e764"} 08:29:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:29:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:29:17 INFO - Contents: 08:29:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a9be0836f67325813b0e1e62ff5096aa65dd23ff0cbcff6948866e9a7f2d5da3f679ceee9fbe7e598e553ac80ab2407ea1c2832edf2a2439481374f8395dcd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91d24aaa676a86febc793363cec588adaec6c6efccb4db196e62cf91617abe8768e2793ba8ee9009de05dbdbcdeda591b920d454697ef6ca590aa3b66827e764"} 08:29:17 INFO - Running post-run listener: copy_logs_to_upload_dir 08:29:17 INFO - Copying logs to upload dir... 08:29:17 INFO - mkdir: /builds/slave/test/build/upload/logs 08:29:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.402065 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-05-06 08:29:16.645554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 08:29:16.649656) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035 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/2a9be0836f67325813b0e1e62ff5096aa65dd23ff0cbcff6948866e9a7f2d5da3f679ceee9fbe7e598e553ac80ab2407ea1c2832edf2a2439481374f8395dcd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91d24aaa676a86febc793363cec588adaec6c6efccb4db196e62cf91617abe8768e2793ba8ee9009de05dbdbcdeda591b920d454697ef6ca590aa3b66827e764"} build_url:https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/DzUVoZqYTJOKfCm7mggS9Q/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/2a9be0836f67325813b0e1e62ff5096aa65dd23ff0cbcff6948866e9a7f2d5da3f679ceee9fbe7e598e553ac80ab2407ea1c2832edf2a2439481374f8395dcd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91d24aaa676a86febc793363cec588adaec6c6efccb4db196e62cf91617abe8768e2793ba8ee9009de05dbdbcdeda591b920d454697ef6ca590aa3b66827e764"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 08:29:16.678210) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:29:16.678580) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1462546595.560957-48838035 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:29:16.761047) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 08:29:16.761419) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 08:29:16.761804) ========= ========= Total master_lag: 0.44 =========