builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1461329824.48 results: success (0) buildid: 20160422030223 builduid: 4499c409e49941e4810cca90538d9a79 revision: 0891f0fa044cba28024849803e170ed7700e01e0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.485001) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.485502) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.485784) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.499754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.500190) ========= 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=0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-22 05:57:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-04-22 05:57:04 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112598 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.624037) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.624371) ========= 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=0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017403 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.674448) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:04.674818) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0891f0fa044cba28024849803e170ed7700e01e0 --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 0891f0fa044cba28024849803e170ed7700e01e0 --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=0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-22 05:57:04,794 truncating revision to first 12 chars 2016-04-22 05:57:04,794 Setting DEBUG logging. 2016-04-22 05:57:04,794 attempt 1/10 2016-04-22 05:57:04,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0891f0fa044c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 05:57:05,387 unpacking tar archive at: mozilla-central-0891f0fa044c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.836442 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:05.521704) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:05.522113) ========= script_repo_revision: 0891f0fa044cba28024849803e170ed7700e01e0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:05.522575) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:05.522873) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:57:05.532973) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-04-22 05:57:05.533459) ========= /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=0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:57:05 INFO - MultiFileLogger online at 20160422 05:57:05 in /builds/slave/test 05:57:05 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 05:57:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:05 INFO - {'append_to_log': False, 05:57:05 INFO - 'base_work_dir': '/builds/slave/test', 05:57:05 INFO - 'blob_upload_branch': 'Firefox', 05:57:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:05 INFO - 'branch': 'Firefox', 05:57:05 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:05 INFO - 'config_files': ('talos/linux_config.py',), 05:57:05 INFO - 'default_actions': ('clobber', 05:57:05 INFO - 'read-buildbot-config', 05:57:05 INFO - 'download-and-extract', 05:57:05 INFO - 'populate-webroot', 05:57:05 INFO - 'create-virtualenv', 05:57:05 INFO - 'install', 05:57:05 INFO - 'run-tests'), 05:57:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:05 INFO - 'download_minidump_stackwalk': True, 05:57:05 INFO - 'download_symbols': 'ondemand', 05:57:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:05 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:05 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:05 INFO - 'installer_path': 'installer.exe', 05:57:05 INFO - 'log_level': 'info', 05:57:05 INFO - 'log_name': 'talos', 05:57:05 INFO - 'log_to_console': True, 05:57:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:57:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:57:05 INFO - 'opt_config_files': (), 05:57:05 INFO - 'pip_index': False, 05:57:05 INFO - 'sps_profile': False, 05:57:05 INFO - 'sps_profile_interval': 0, 05:57:05 INFO - 'suite': 'dromaeojs', 05:57:05 INFO - 'system_bits': '32', 05:57:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:05 INFO - 'title': 'talos-linux64-ix-029', 05:57:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:05 INFO - 'use_talos_json': True, 05:57:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:05 INFO - 'work_dir': 'build'} 05:57:05 INFO - ##### 05:57:05 INFO - ##### Running clobber step. 05:57:05 INFO - ##### 05:57:05 INFO - Running pre-action listener: _resource_record_pre_action 05:57:05 INFO - Running main action method: clobber 05:57:05 INFO - rmtree: /builds/slave/test/build 05:57:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:06 INFO - Running post-action listener: _resource_record_post_action 05:57:06 INFO - ##### 05:57:06 INFO - ##### Running read-buildbot-config step. 05:57:06 INFO - ##### 05:57:06 INFO - Running pre-action listener: _resource_record_pre_action 05:57:06 INFO - Running main action method: read_buildbot_config 05:57:06 INFO - Using buildbot properties: 05:57:06 INFO - { 05:57:06 INFO - "project": "", 05:57:06 INFO - "product": "firefox", 05:57:06 INFO - "script_repo_revision": "production", 05:57:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:57:06 INFO - "repository": "", 05:57:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:57:06 INFO - "buildid": "20160422030223", 05:57:06 INFO - "stage_platform": "linux64-pgo", 05:57:06 INFO - "basedir": "/builds/slave/test", 05:57:06 INFO - "nightly_build": "True", 05:57:06 INFO - "buildnumber": 415, 05:57:06 INFO - "slavename": "talos-linux64-ix-029", 05:57:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:57:06 INFO - "platform": "ubuntu64_hw", 05:57:06 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:57:06 INFO - "branch": "mozilla-central", 05:57:06 INFO - "repo_path": "mozilla-central", 05:57:06 INFO - "revision": "0891f0fa044cba28024849803e170ed7700e01e0", 05:57:06 INFO - "pgo_build": "True", 05:57:06 INFO - "builduid": "4499c409e49941e4810cca90538d9a79", 05:57:06 INFO - "slavebuilddir": "test" 05:57:06 INFO - } 05:57:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 05:57:06 INFO - Running post-action listener: _resource_record_post_action 05:57:06 INFO - ##### 05:57:06 INFO - ##### Running download-and-extract step. 05:57:06 INFO - ##### 05:57:06 INFO - Running pre-action listener: _resource_record_pre_action 05:57:06 INFO - Running main action method: download_and_extract 05:57:06 INFO - mkdir: /builds/slave/test/build/tests 05:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:06 INFO - https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:57:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:57:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:57:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:57:06 INFO - Downloaded 1361 bytes. 05:57:06 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:57:06 INFO - Using the following test package requirements: 05:57:06 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 05:57:06 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:57:06 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:57:06 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'jsshell-linux-x86_64.zip'], 05:57:06 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:57:06 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 05:57:06 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:57:06 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:57:06 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:57:06 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:57:06 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:57:06 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:06 INFO - https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:57:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:57:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:57:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:57:08 INFO - Downloaded 23853381 bytes. 05:57:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:57:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:57:09 INFO - Return code: 0 05:57:09 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:57: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')]} 05:57:09 INFO - https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:57:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:57:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:57:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:57:10 INFO - Downloaded 23853381 bytes. 05:57:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:57:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:57:12 INFO - Return code: 0 05:57: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')]} 05:57:12 INFO - https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:57:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:57:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:57:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:57:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:57:13 INFO - Downloaded 10845952 bytes. 05:57:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:57:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:57:13 INFO - Return code: 0 05:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:13 INFO - https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:57:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:57:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:57:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:57:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:57:14 INFO - Downloaded 58343362 bytes. 05:57:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:57:14 INFO - mkdir: /builds/slave/test/properties 05:57:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:14 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:14 INFO - Contents: 05:57:14 INFO - build_url:https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:57:15 INFO - Running post-action listener: _resource_record_post_action 05:57:15 INFO - Running post-action listener: set_extra_try_arguments 05:57:15 INFO - ##### 05:57:15 INFO - ##### Running populate-webroot step. 05:57:15 INFO - ##### 05:57:15 INFO - Running pre-action listener: _resource_record_pre_action 05:57:15 INFO - Running main action method: populate_webroot 05:57:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:15 INFO - '--tppagecycles', 05:57:15 INFO - '7'], 05:57:15 INFO - 'tests': ['tsvgm']}}, 05:57:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['damp', 'tps']}, 05:57:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'tests': ['damp', 'tps']}, 05:57:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['dromaeo_dom']}, 05:57:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['a11yr', 05:57:15 INFO - 'ts_paint', 05:57:15 INFO - 'tpaint', 05:57:15 INFO - 'sessionrestore', 05:57:15 INFO - 'sessionrestore_no_auto_restore', 05:57:15 INFO - 'tabpaint']}, 05:57:15 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:15 INFO - 'ts_paint', 05:57:15 INFO - 'tpaint', 05:57:15 INFO - 'sessionrestore', 05:57:15 INFO - 'sessionrestore_no_auto_restore', 05:57:15 INFO - 'tabpaint']}, 05:57:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:15 INFO - 'ts_paint', 05:57:15 INFO - 'tpaint', 05:57:15 INFO - 'sessionrestore', 05:57:15 INFO - 'sessionrestore_no_auto_restore', 05:57:15 INFO - 'tabpaint']}, 05:57:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:15 INFO - 'ts_paint', 05:57:15 INFO - 'tpaint', 05:57:15 INFO - 'sessionrestore', 05:57:15 INFO - 'sessionrestore_no_auto_restore', 05:57:15 INFO - 'tabpaint']}, 05:57:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['a11yr', 05:57:15 INFO - 'ts_paint', 05:57:15 INFO - 'tpaint', 05:57:15 INFO - 'sessionrestore', 05:57:15 INFO - 'sessionrestore_no_auto_restore', 05:57:15 INFO - 'tabpaint']}, 05:57:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['a11yr', 05:57:15 INFO - 'ts_paint', 05:57:15 INFO - 'tpaint', 05:57:15 INFO - 'sessionrestore', 05:57:15 INFO - 'sessionrestore_no_auto_restore', 05:57:15 INFO - 'tabpaint']}, 05:57:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['tsvgx', 05:57:15 INFO - 'tsvgr_opacity', 05:57:15 INFO - 'tart', 05:57:15 INFO - 'tscrollx', 05:57:15 INFO - 'cart']}, 05:57:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:15 INFO - 'tsvgr_opacity', 05:57:15 INFO - 'tart', 05:57:15 INFO - 'tscrollx', 05:57:15 INFO - 'cart']}, 05:57:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'talos_options': ['--disable-e10s'], 05:57:15 INFO - 'tests': ['tp5o']}, 05:57:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'tests': ['tp5o']}, 05:57:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'talos_options': ['--disable-e10s', 05:57:15 INFO - '--xperf_path', 05:57:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:15 INFO - 'tests': ['tp5n']}, 05:57:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:15 INFO - 'talos_options': ['--xperf_path', 05:57:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:15 INFO - 'tests': ['tp5n']}}, 05:57:15 INFO - 'talos.zip': {'path': '', 05:57:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:15 INFO - Running post-action listener: _resource_record_post_action 05:57:15 INFO - ##### 05:57:15 INFO - ##### Running create-virtualenv step. 05:57:15 INFO - ##### 05:57:15 INFO - Running pre-action listener: _resource_record_pre_action 05:57:15 INFO - Running main action method: create_virtualenv 05:57:15 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:15 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 05:57:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:15 INFO - Using real prefix '/usr' 05:57:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:16 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:18 INFO - Installing pip.................done. 05:57:18 INFO - Return code: 0 05:57:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:18 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:18 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 05:57:18 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 05:57:18 INFO - Using env: {'DISPLAY': ':0', 05:57:18 INFO - 'HOME': '/home/cltbld', 05:57:18 INFO - 'LANG': 'en_US.UTF-8', 05:57:18 INFO - 'LANGUAGE': 'en_US:en', 05:57:18 INFO - 'LOGNAME': 'cltbld', 05:57:18 INFO - 'MAIL': '/var/mail/cltbld', 05:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:18 INFO - 'MOZ_NO_REMOTE': '1', 05:57:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:18 INFO - 'NO_EM_RESTART': '1', 05:57:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:18 INFO - 'PWD': '/builds/slave/test', 05:57:18 INFO - 'SHELL': '/bin/bash', 05:57:18 INFO - 'SHLVL': '1', 05:57:18 INFO - 'TERM': 'linux', 05:57:18 INFO - 'TMOUT': '86400', 05:57:18 INFO - 'USER': 'cltbld', 05:57:18 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:18 INFO - '_': '/tools/buildbot/bin/python'} 05:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:18 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 05:57:18 INFO - Cleaning up... 05:57:18 INFO - Return code: 0 05:57:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:18 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:18 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 05:57:18 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 05:57:18 INFO - Using env: {'DISPLAY': ':0', 05:57:18 INFO - 'HOME': '/home/cltbld', 05:57:18 INFO - 'LANG': 'en_US.UTF-8', 05:57:18 INFO - 'LANGUAGE': 'en_US:en', 05:57:18 INFO - 'LOGNAME': 'cltbld', 05:57:18 INFO - 'MAIL': '/var/mail/cltbld', 05:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:18 INFO - 'MOZ_NO_REMOTE': '1', 05:57:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:18 INFO - 'NO_EM_RESTART': '1', 05:57:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:18 INFO - 'PWD': '/builds/slave/test', 05:57:18 INFO - 'SHELL': '/bin/bash', 05:57:18 INFO - 'SHLVL': '1', 05:57:18 INFO - 'TERM': 'linux', 05:57:18 INFO - 'TMOUT': '86400', 05:57:18 INFO - 'USER': 'cltbld', 05:57:18 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:18 INFO - '_': '/tools/buildbot/bin/python'} 05:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:18 INFO - Downloading/unpacking psutil>=0.7.1 05:57: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 05:57: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 05:57: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 05:57: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 05:57: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 05:57: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 05:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:20 INFO - Installing collected packages: psutil 05:57:20 INFO - Running setup.py install for psutil 05:57:20 INFO - building 'psutil._psutil_linux' extension 05:57:20 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 05:57:21 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 05:57:21 INFO - building 'psutil._psutil_posix' extension 05:57:21 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 05:57:21 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 05:57:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:21 INFO - Successfully installed psutil 05:57:21 INFO - Cleaning up... 05:57:21 INFO - Return code: 0 05:57:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:21 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 05:57:21 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 05:57:21 INFO - Using env: {'DISPLAY': ':0', 05:57:21 INFO - 'HOME': '/home/cltbld', 05:57:21 INFO - 'LANG': 'en_US.UTF-8', 05:57:21 INFO - 'LANGUAGE': 'en_US:en', 05:57:21 INFO - 'LOGNAME': 'cltbld', 05:57:21 INFO - 'MAIL': '/var/mail/cltbld', 05:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:21 INFO - 'MOZ_NO_REMOTE': '1', 05:57:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:21 INFO - 'NO_EM_RESTART': '1', 05:57:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:21 INFO - 'PWD': '/builds/slave/test', 05:57:21 INFO - 'SHELL': '/bin/bash', 05:57:21 INFO - 'SHLVL': '1', 05:57:21 INFO - 'TERM': 'linux', 05:57:21 INFO - 'TMOUT': '86400', 05:57:21 INFO - 'USER': 'cltbld', 05:57:21 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:21 INFO - '_': '/tools/buildbot/bin/python'} 05:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:57: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 05:57: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 05:57: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 05:57: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 05:57: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 05:57: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 05:57:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:57:23 INFO - Installing collected packages: mozsystemmonitor 05:57:23 INFO - Running setup.py install for mozsystemmonitor 05:57:23 INFO - Successfully installed mozsystemmonitor 05:57:23 INFO - Cleaning up... 05:57:23 INFO - Return code: 0 05:57:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:57:23 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')]} 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:23 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')]} 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:23 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:23 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 05:57:23 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 05:57:23 INFO - Using env: {'DISPLAY': ':0', 05:57:23 INFO - 'HOME': '/home/cltbld', 05:57:23 INFO - 'LANG': 'en_US.UTF-8', 05:57:23 INFO - 'LANGUAGE': 'en_US:en', 05:57:23 INFO - 'LOGNAME': 'cltbld', 05:57:23 INFO - 'MAIL': '/var/mail/cltbld', 05:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:23 INFO - 'MOZ_NO_REMOTE': '1', 05:57:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:23 INFO - 'NO_EM_RESTART': '1', 05:57:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:23 INFO - 'PWD': '/builds/slave/test', 05:57:23 INFO - 'SHELL': '/bin/bash', 05:57:23 INFO - 'SHLVL': '1', 05:57:23 INFO - 'TERM': 'linux', 05:57:23 INFO - 'TMOUT': '86400', 05:57:23 INFO - 'USER': 'cltbld', 05:57:23 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:23 INFO - '_': '/tools/buildbot/bin/python'} 05:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:23 INFO - Downloading/unpacking blobuploader==1.2.4 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:25 INFO - Downloading blobuploader-1.2.4.tar.gz 05:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:57:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:57:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:26 INFO - Downloading docopt-0.6.1.tar.gz 05:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:57:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:57:26 INFO - Running setup.py install for blobuploader 05:57:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:57:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:57:26 INFO - Running setup.py install for requests 05:57:26 INFO - Running setup.py install for docopt 05:57:26 INFO - Successfully installed blobuploader requests docopt 05:57:26 INFO - Cleaning up... 05:57:27 INFO - Return code: 0 05:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:27 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')]} 05:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:27 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')]} 05:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:27 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:27 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 05:57:27 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 05:57:27 INFO - Using env: {'DISPLAY': ':0', 05:57:27 INFO - 'HOME': '/home/cltbld', 05:57:27 INFO - 'LANG': 'en_US.UTF-8', 05:57:27 INFO - 'LANGUAGE': 'en_US:en', 05:57:27 INFO - 'LOGNAME': 'cltbld', 05:57:27 INFO - 'MAIL': '/var/mail/cltbld', 05:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:27 INFO - 'MOZ_NO_REMOTE': '1', 05:57:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:27 INFO - 'NO_EM_RESTART': '1', 05:57:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:27 INFO - 'PWD': '/builds/slave/test', 05:57:27 INFO - 'SHELL': '/bin/bash', 05:57:27 INFO - 'SHLVL': '1', 05:57:27 INFO - 'TERM': 'linux', 05:57:27 INFO - 'TMOUT': '86400', 05:57:27 INFO - 'USER': 'cltbld', 05:57:27 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:27 INFO - '_': '/tools/buildbot/bin/python'} 05:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:27 INFO - Running setup.py (path:/tmp/pip-e7B6Wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:27 INFO - Running setup.py (path:/tmp/pip-P8fuw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:27 INFO - Running setup.py (path:/tmp/pip-vf8VRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:27 INFO - Running setup.py (path:/tmp/pip-oY1p7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:27 INFO - Running setup.py (path:/tmp/pip-2YyWrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:27 INFO - Running setup.py (path:/tmp/pip-QNIOQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:27 INFO - Running setup.py (path:/tmp/pip-DdKp_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:27 INFO - Running setup.py (path:/tmp/pip-BNSSOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:27 INFO - Running setup.py (path:/tmp/pip-Go3mGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:27 INFO - Running setup.py (path:/tmp/pip-MkINct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:28 INFO - Running setup.py (path:/tmp/pip-9Pvcw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:28 INFO - Running setup.py (path:/tmp/pip-XTVHDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:28 INFO - Running setup.py (path:/tmp/pip-3qdJR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:28 INFO - Running setup.py (path:/tmp/pip-MPymUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:28 INFO - Running setup.py (path:/tmp/pip-rD0Z45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:28 INFO - Running setup.py (path:/tmp/pip-gbURa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:28 INFO - Running setup.py (path:/tmp/pip-fgZPYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:28 INFO - Running setup.py install for manifestparser 05:57:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Running setup.py install for mozcrash 05:57:28 INFO - Running setup.py install for mozdebug 05:57:28 INFO - Running setup.py install for mozdevice 05:57:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:28 INFO - Running setup.py install for mozfile 05:57:29 INFO - Running setup.py install for mozhttpd 05:57:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Running setup.py install for mozinfo 05:57:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Running setup.py install for mozInstall 05:57:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Running setup.py install for mozleak 05:57:29 INFO - Running setup.py install for mozlog 05:57:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Running setup.py install for moznetwork 05:57:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Running setup.py install for mozprocess 05:57:29 INFO - Running setup.py install for mozprofile 05:57:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Running setup.py install for mozrunner 05:57:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:29 INFO - Running setup.py install for mozscreenshot 05:57:30 INFO - Running setup.py install for moztest 05:57:30 INFO - Running setup.py install for mozversion 05:57:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:30 INFO - Cleaning up... 05:57:30 INFO - Return code: 0 05:57:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:30 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:30 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 05:57:30 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 05:57:30 INFO - Using env: {'DISPLAY': ':0', 05:57:30 INFO - 'HOME': '/home/cltbld', 05:57:30 INFO - 'LANG': 'en_US.UTF-8', 05:57:30 INFO - 'LANGUAGE': 'en_US:en', 05:57:30 INFO - 'LOGNAME': 'cltbld', 05:57:30 INFO - 'MAIL': '/var/mail/cltbld', 05:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:30 INFO - 'MOZ_NO_REMOTE': '1', 05:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:30 INFO - 'NO_EM_RESTART': '1', 05:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:30 INFO - 'PWD': '/builds/slave/test', 05:57:30 INFO - 'SHELL': '/bin/bash', 05:57:30 INFO - 'SHLVL': '1', 05:57:30 INFO - 'TERM': 'linux', 05:57:30 INFO - 'TMOUT': '86400', 05:57:30 INFO - 'USER': 'cltbld', 05:57:30 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:30 INFO - '_': '/tools/buildbot/bin/python'} 05:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:30 INFO - Running setup.py (path:/tmp/pip-28w0Ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:30 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)) 05:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:30 INFO - Running setup.py (path:/tmp/pip-F922N5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:30 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)) 05:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:30 INFO - Running setup.py (path:/tmp/pip-IUrQFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:30 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)) 05:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:30 INFO - Running setup.py (path:/tmp/pip-DhfbWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:30 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)) 05:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:30 INFO - Running setup.py (path:/tmp/pip-XLoT0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:30 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)) 05:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:30 INFO - Running setup.py (path:/tmp/pip-FjebjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:30 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)) 05:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:30 INFO - Running setup.py (path:/tmp/pip-AfSH0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:31 INFO - Running setup.py (path:/tmp/pip-eMF6GI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:31 INFO - Running setup.py (path:/tmp/pip-mKB9NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:31 INFO - Running setup.py (path:/tmp/pip-Fpqx1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:31 INFO - Running setup.py (path:/tmp/pip-FOjCrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:31 INFO - Running setup.py (path:/tmp/pip-pVsc7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:31 INFO - Running setup.py (path:/tmp/pip-AVtgtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:31 INFO - Running setup.py (path:/tmp/pip-0jHUfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:31 INFO - Running setup.py (path:/tmp/pip-6AuR0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:31 INFO - Running setup.py (path:/tmp/pip-QC0mqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:31 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)) 05:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:31 INFO - Running setup.py (path:/tmp/pip-aH33YT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:31 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)) 05:57:31 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)) 05:57:31 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)) 05:57:31 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)) 05:57:31 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)) 05:57:31 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)) 05:57:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:31 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 05:57:31 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 05:57:31 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 05:57:31 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 05:57:31 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 05:57:31 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 05:57:33 INFO - Downloading blessings-1.6.tar.gz 05:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:33 INFO - Installing collected packages: blessings 05:57:33 INFO - Running setup.py install for blessings 05:57:33 INFO - Successfully installed blessings 05:57:33 INFO - Cleaning up... 05:57:33 INFO - Return code: 0 05:57:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:34 INFO - Reading from file tmpfile_stdout 05:57:34 INFO - Current package versions: 05:57:34 INFO - argparse == 1.2.1 05:57:34 INFO - blessings == 1.6 05:57:34 INFO - blobuploader == 1.2.4 05:57:34 INFO - docopt == 0.6.1 05:57:34 INFO - manifestparser == 1.1 05:57:34 INFO - mozInstall == 1.12 05:57:34 INFO - mozcrash == 0.17 05:57:34 INFO - mozdebug == 0.1 05:57:34 INFO - mozdevice == 0.48 05:57:34 INFO - mozfile == 1.2 05:57:34 INFO - mozhttpd == 0.7 05:57:34 INFO - mozinfo == 0.9 05:57:34 INFO - mozleak == 0.1 05:57:34 INFO - mozlog == 3.1 05:57:34 INFO - moznetwork == 0.27 05:57:34 INFO - mozprocess == 0.22 05:57:34 INFO - mozprofile == 0.28 05:57:34 INFO - mozrunner == 6.11 05:57:34 INFO - mozscreenshot == 0.1 05:57:34 INFO - mozsystemmonitor == 0.0 05:57:34 INFO - moztest == 0.7 05:57:34 INFO - mozversion == 1.4 05:57:34 INFO - psutil == 3.1.1 05:57:34 INFO - requests == 1.2.3 05:57:34 INFO - wsgiref == 0.1.2 05:57:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:34 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')]} 05:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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')]} 05:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:34 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 05:57:34 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 05:57:34 INFO - Using env: {'DISPLAY': ':0', 05:57:34 INFO - 'HOME': '/home/cltbld', 05:57:34 INFO - 'LANG': 'en_US.UTF-8', 05:57:34 INFO - 'LANGUAGE': 'en_US:en', 05:57:34 INFO - 'LOGNAME': 'cltbld', 05:57:34 INFO - 'MAIL': '/var/mail/cltbld', 05:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:34 INFO - 'MOZ_NO_REMOTE': '1', 05:57:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:34 INFO - 'NO_EM_RESTART': '1', 05:57:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:34 INFO - 'PWD': '/builds/slave/test', 05:57:34 INFO - 'SHELL': '/bin/bash', 05:57:34 INFO - 'SHLVL': '1', 05:57:34 INFO - 'TERM': 'linux', 05:57:34 INFO - 'TMOUT': '86400', 05:57:34 INFO - 'USER': 'cltbld', 05:57:34 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:34 INFO - '_': '/tools/buildbot/bin/python'} 05:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 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)) 05:57:34 INFO - Cleaning up... 05:57:34 INFO - Return code: 0 05:57:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57:34 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')]} 05:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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')]} 05:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:34 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 05:57:34 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 05:57:34 INFO - Using env: {'DISPLAY': ':0', 05:57:34 INFO - 'HOME': '/home/cltbld', 05:57:34 INFO - 'LANG': 'en_US.UTF-8', 05:57:34 INFO - 'LANGUAGE': 'en_US:en', 05:57:34 INFO - 'LOGNAME': 'cltbld', 05:57:34 INFO - 'MAIL': '/var/mail/cltbld', 05:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:34 INFO - 'MOZ_NO_REMOTE': '1', 05:57:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:34 INFO - 'NO_EM_RESTART': '1', 05:57:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:34 INFO - 'PWD': '/builds/slave/test', 05:57:34 INFO - 'SHELL': '/bin/bash', 05:57:34 INFO - 'SHLVL': '1', 05:57:34 INFO - 'TERM': 'linux', 05:57:34 INFO - 'TMOUT': '86400', 05:57:34 INFO - 'USER': 'cltbld', 05:57:34 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:34 INFO - '_': '/tools/buildbot/bin/python'} 05:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:34 INFO - Downloading/unpacking jsonschema 05:57:34 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 05:57:34 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 05:57:34 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 05:57:34 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 05:57:34 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 05:57:34 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 05:57:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:57:36 INFO - Downloading/unpacking functools32 (from jsonschema) 05:57:36 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 05:57:36 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 05:57:36 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 05:57:36 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 05:57:36 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 05:57:36 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 05:57:36 INFO - Downloading functools32-3.2.3-2.tar.gz 05:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:57:36 INFO - warning: no files found matching '*.txt' 05:57:36 INFO - no previously-included directories found matching 'build' 05:57:36 INFO - no previously-included directories found matching 'dist' 05:57:36 INFO - no previously-included directories found matching '.git*' 05:57:36 INFO - Installing collected packages: jsonschema, functools32 05:57:36 INFO - Running setup.py install for functools32 05:57:36 INFO - warning: no files found matching '*.txt' 05:57:36 INFO - no previously-included directories found matching 'build' 05:57:36 INFO - no previously-included directories found matching 'dist' 05:57:36 INFO - no previously-included directories found matching '.git*' 05:57:36 INFO - Successfully installed jsonschema functools32 05:57:36 INFO - Cleaning up... 05:57:36 INFO - Return code: 0 05:57:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:36 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 0x7f00f9071f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13909a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, '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': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', '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 05:57:36 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 05:57:36 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 05:57:36 INFO - Using env: {'DISPLAY': ':0', 05:57:36 INFO - 'HOME': '/home/cltbld', 05:57:36 INFO - 'LANG': 'en_US.UTF-8', 05:57:36 INFO - 'LANGUAGE': 'en_US:en', 05:57:36 INFO - 'LOGNAME': 'cltbld', 05:57:36 INFO - 'MAIL': '/var/mail/cltbld', 05:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:36 INFO - 'MOZ_NO_REMOTE': '1', 05:57:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:36 INFO - 'NO_EM_RESTART': '1', 05:57:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:36 INFO - 'PWD': '/builds/slave/test', 05:57:36 INFO - 'SHELL': '/bin/bash', 05:57:36 INFO - 'SHLVL': '1', 05:57:36 INFO - 'TERM': 'linux', 05:57:36 INFO - 'TMOUT': '86400', 05:57:36 INFO - 'USER': 'cltbld', 05:57:36 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:36 INFO - '_': '/tools/buildbot/bin/python'} 05:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:37 INFO - Downloading/unpacking flake8 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:57:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:57:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 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 05:57:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:57:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:57:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:57:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:57:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:57:39 INFO - no previously-included directories found matching 'docs/_build' 05:57:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:57:39 INFO - Running setup.py install for pep8 05:57:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:57:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:57:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:57:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:57:39 INFO - no previously-included directories found matching 'docs/_build' 05:57:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:57:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:57:39 INFO - Cleaning up... 05:57:39 INFO - Return code: 0 05:57:39 INFO - Running post-action listener: _resource_record_post_action 05:57:39 INFO - Running post-action listener: _start_resource_monitoring 05:57:39 INFO - Starting resource monitoring. 05:57:39 INFO - ##### 05:57:39 INFO - ##### Running install step. 05:57:39 INFO - ##### 05:57:39 INFO - Running pre-action listener: _resource_record_pre_action 05:57:39 INFO - Running main action method: install 05:57:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:40 INFO - Reading from file tmpfile_stdout 05:57:40 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:40 INFO - Reading from file tmpfile_stdout 05:57:40 INFO - Output received: 05:57:40 INFO - Usage: mozinstall [options] installer 05:57:40 INFO - Options: 05:57:40 INFO - -h, --help show this help message and exit 05:57:40 INFO - -d DEST, --destination=DEST 05:57:40 INFO - Directory to install application into. [default: 05:57:40 INFO - "/builds/slave/test"] 05:57:40 INFO - --app=APP Application being installed. [default: firefox] 05:57:40 INFO - mkdir: /builds/slave/test/build/application 05:57:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:57:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:57:56 INFO - Reading from file tmpfile_stdout 05:57:56 INFO - Output received: 05:57:56 INFO - /builds/slave/test/build/application/firefox/firefox 05:57:56 INFO - Running post-action listener: _resource_record_post_action 05:57:56 INFO - ##### 05:57:56 INFO - ##### Running run-tests step. 05:57:56 INFO - ##### 05:57:56 INFO - Running pre-action listener: _resource_record_pre_action 05:57:56 INFO - Running main action method: run_tests 05:57:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:57:56 INFO - Python 2.7.3 05:57:56 INFO - Return code: 0 05:57:56 INFO - grabbing minidump binary from tooltool 05:57:56 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')]} 05:57:56 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 0x141f810>, '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 0x1420710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1420ba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:57:56 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 05:57:56 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 05:57:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:57:56 INFO - Return code: 0 05:57:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:57:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:57:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:57:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:57:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:57:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:57:57 INFO - Return code: 0 05:57:57 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/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 05:57:57 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/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 05:57:57 INFO - Using env: {'DISPLAY': ':0', 05:57:57 INFO - 'HOME': '/home/cltbld', 05:57:57 INFO - 'LANG': 'en_US.UTF-8', 05:57:57 INFO - 'LANGUAGE': 'en_US:en', 05:57:57 INFO - 'LOGNAME': 'cltbld', 05:57:57 INFO - 'MAIL': '/var/mail/cltbld', 05:57:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:57:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:57 INFO - 'MOZ_NO_REMOTE': '1', 05:57:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:57 INFO - 'NO_EM_RESTART': '1', 05:57:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:57 INFO - 'PWD': '/builds/slave/test', 05:57:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:57:57 INFO - 'SHELL': '/bin/bash', 05:57:57 INFO - 'SHLVL': '1', 05:57:57 INFO - 'TERM': 'linux', 05:57:57 INFO - 'TMOUT': '86400', 05:57:57 INFO - 'USER': 'cltbld', 05:57:57 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712', 05:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:57 INFO - '_': '/tools/buildbot/bin/python'} 05:57:57 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/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 05:57:58 INFO - mozversion application_buildid: 20160422030223 05:57:58 INFO - mozversion application_changeset: 0891f0fa044cba28024849803e170ed7700e01e0 05:57:58 INFO - mozversion application_display_name: Nightly 05:57:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:57:58 INFO - mozversion application_name: Firefox 05:57:58 INFO - mozversion application_remotingname: firefox 05:57:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:57:58 INFO - mozversion application_vendor: Mozilla 05:57:58 INFO - mozversion application_version: 48.0a1 05:57:58 INFO - mozversion platform_buildid: 20160422030223 05:57:58 INFO - mozversion platform_changeset: 0891f0fa044cba28024849803e170ed7700e01e0 05:57:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:57:58 INFO - mozversion platform_version: 48.0a1 05:57:58 INFO - using testdate: 1461329878 05:57:58 INFO - actual date: 1461329878 05:57:58 INFO - starting webserver on 'localhost:59955' 05:57:58 INFO - SUITE-START | Running 2 tests 05:57:58 INFO - TEST-START | dromaeo_css 05:57:58 INFO - operating with platform_type : linux_ 05:57:58 INFO - Initialising browser for dromaeo_css test... 05:57:58 INFO - TEST-INFO | started process 25014 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvjJUFN/profile http://localhost:59955/getInfo.html) 05:58:01 INFO - PROCESS | 25014 | __metrics Screen width/height:1600/1200 05:58:01 INFO - PROCESS | 25014 | colorDepth:24 05:58:01 INFO - PROCESS | 25014 | Browser inner width/height: 1024/697 05:58:01 INFO - PROCESS | 25014 | __metrics 05:58:02 ERROR - PROCESS | 25014 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:58:03 INFO - TEST-INFO | 25014: exit 0 05:58:03 INFO - Browser initialized. 05:58:03 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:03 INFO - TEST-INFO | started process 25095 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvjJUFN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:15 INFO - PROCESS | 25095 | RSS: Main: 153554944 05:58:15 INFO - PROCESS | 25095 | 05:59:06 INFO - PROCESS | 25095 | 1461329946490 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] 06:01:13 INFO - PROCESS | 25095 | Cycle 1(1): loaded http://localhost:59955/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59955/tests/dromaeo/cssquery-ext.html) 06:01:14 INFO - PROCESS | 25095 | RSS: Main: 219295744 06:01:14 INFO - PROCESS | 25095 | 06:04:16 INFO - PROCESS | 25095 | Cycle 1(1): loaded http://localhost:59955/tests/dromaeo/cssquery-ext.html (next: http://localhost:59955/tests/dromaeo/cssquery-jquery.html) 06:04:17 INFO - PROCESS | 25095 | RSS: Main: 236855296 06:04:17 INFO - PROCESS | 25095 | 06:07:19 INFO - PROCESS | 25095 | Cycle 1(1): loaded http://localhost:59955/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59955/tests/dromaeo/cssquery-mootools.html) 06:07:20 INFO - PROCESS | 25095 | RSS: Main: 227766272 06:07:20 INFO - PROCESS | 25095 | 06:10:22 INFO - PROCESS | 25095 | Cycle 1(1): loaded http://localhost:59955/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59955/tests/dromaeo/cssquery-prototype.html) 06:10:22 INFO - PROCESS | 25095 | RSS: Main: 231542784 06:10:22 INFO - PROCESS | 25095 | 06:13:25 INFO - PROCESS | 25095 | Cycle 1(1): loaded http://localhost:59955/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59955/tests/dromaeo/cssquery-yui.html) 06:13:25 INFO - PROCESS | 25095 | RSS: Main: 223559680 06:13:25 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | Cycle 1(1): loaded http://localhost:59955/tests/dromaeo/cssquery-yui.html (next: http://localhost:59955/tests/dromaeo/cssquery-dojo.html) 06:16:28 INFO - PROCESS | 25095 | RSS: Main: 274272256 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | __start_tp_report 06:16:28 INFO - PROCESS | 25095 | _x_x_mozilla_page_load 06:16:28 INFO - PROCESS | 25095 | _x_x_mozilla_page_load_details 06:16:28 INFO - PROCESS | 25095 | |i|pagename|runs| 06:16:28 INFO - PROCESS | 25095 | |0;dojo.html;3046.9999999999995;3103.8913638022673;3134.1224457151993;3134.9216269593257;3169.2869104451493;5062.519060689237;5127.384713834345;5191.636585439021;5192.247124166998;5200.6099542534275;5250.789968401259;5261.368635763704;5348.652337598053;5354.437784032669;5367.677939323643;4722.7402492862875;4738.076075165344;4761.262004389325;4762.78567464465;4944.035912996967;4930.654854160211;4932.630052746041;4934.481879402647;4942.703437793726;4956.752162391884;4594.47163576188;4663.860084197479;4685.929711054337;4851.490593487686;4911.45973942866;4736.100140973204;4737.857864264043;4771.6898401603785;4784.186688263004;4848.927266091011;4726.598239149676;4750.71495710255;4764.880877978044;4778.784954677013;4802.59177969873;4931.211006238985;5117.8464646060675;5156.690598564098;5163.8192663256605;5199;5535.778568857241;5538.335399752055;5538.418466061086;5577.665340079568;5588.608797384144;5562.9986602411545;5566.582506312043;5586.608937374385;5589.469000444951;5599.636023658449;5071.1632580624155;5185.481451854822;5219.086659834514;5229.08491014071;5229.973850130801;5679.488846003879;5680.715964201773;5684.261046064068;5686.545076393879;5707.857303567444;5442.972785136049;5448.83653490396;5453.836384908459;5456.454354564591;5460.836174914759;5426.782928682887;5503.972480137573;5526.005319042531;5584.441555844383;5586.050371436865;5551.805686800943;5552.833414997556;5553.278073850373;5554.250176226158;5556.111022236423;5541.5289700375915;5548.861278468071;5548.9722551386985;5549.223108764776;5598.692071936001;4125.484314460692;4230.555791641895;4274.914501710011;4311.805968731414;4318.935215971763;3790.166163444093;3811.5426148862316;3817.370133927871;3830.80845957701;3853.5953724858487;5170.8965820684125;5269.710165940834;5279.656822306538;5283.815066472655;5313.468653134656;5572.804951826666;5651.124075768263;5656;5666.433356664301;5707.6575405475805;5269.78920843162;5304.496072873071;5309.867253318698;5322.0952438085615;5322.707251101149;4701.459332176775;4808;4821.63837712166;4822.758862056883;4864.5621894029;5772.971135144381;5799.855003624859;5801.129830525455;5801.53587712982;5850.4149585042005;5597.384287728428;5610.523105535912;5610.85972850682;5614.63504872182;5616.185653080199;5664.943350566442;5730.054541000855;5733.655980641175;5785.884282314246;5789.073748200268;5450.1007333789485;5457.154141108134;5459.5632349412745;5463.235147079333;5504.669719816824;5515.172724091258;5519.917201241904;5520.06158952971;5521.4478552144465;5555.444455554412;5359.3032905722;5363.19552067193;5386.407495175449;5422.376426710979;5473.261109750133;5471.945280547144;5473.72631368422;5475.342958844964;5476.315460567429;5481.177823326533;5347;5393.379761327497;5393.460653934575;5394.622376433612;5442.891142177055;5283.313169287968;5319.414864364994;5514.641548299348;5559.1661250811085;5596.552275817843;5316.521513063843;5408.729563521887;5413.079776438094;5414.783408663609;5467.261919640798 06:16:28 INFO - PROCESS | 25095 | |1;ext.html;610.9114178444125;646.1051443750406;653.0335104046014;657.3623585122435;657.9702765172505;36920.63079369206;37934.96520278378;37959.240815183715;37984;38007.23985520288;40375;41214.58785412145;41245;41270.38094428578;41299;8548.273396761282;8781.736547903542;8803.163699448542;8810.38327317088;8815.426997245191;8670.13298670131;8800.603972821238;8802.691905783298;8804.64781408743;8805.95597022014;8761.211490965812;8810.691625793099;8851.690190843321;8857.689980850671;8865.157810008071;8730.476171429702;8790.033096359395;8801.075887031897;8801.47191168532;8806.031336552975;8710.782230444227;8825.69110081144;8826.86759698605;8828.249598784047;8830.293576513928;41069.58930410688;41596.79201604003;41620.583794161976;41693.79153104215;41745.373819392735;3614.34941710492;3712.628737126293;3758.49260349852;3780.7731536107644;4038.858639947617;31948;32477.35045299081;32488.512672309935;32506.187345316557;32636.347273054405;4104.548499665035;4153.185540243653;4195.97728746603;4225.830966761326;4228.640565551932;278756;278958;280613;280694;281395.5930220356;54646.453535464934;55449.336519904464;55512.72243638756;55582;55593.722031390396;53054.7347263269;61765;62981.27640426986;63062.693730627485;63146;16370.754438683429;16489;16667.49997500077;16670.666586668198;16707.582310442336;10620.362778233331;12888.871111288767;12958.611241662766;12964.935175324063;12975.870241297655;15596.376144954302;15816.367345306058;15910.204489775697;15952.601184970237;16152.676946461004;6443.970149402923;6529.412352888168;6613.338666133444;6641.767538136239;6747.291534388916;989.7871957529165;1017.8574999500075;1036.3937096798456;1090.8309212072143;1207.486818102303;30282.394352113275;30484.237894052825;30638.54042189369;32719.836400817843;33099.17252068718;1119.4906317625462;1213.6419756171952;1214.3745970825032;1232.89520390766;1244.4897591987242;15365.25061811652;15594.454194103151;15617.765733514007;15761.369545218293;15836.683266334609;338342;342304;343403;344516;344607;50305.49694503081;50889.23666145011;50899.49100509022;54131.18803217955;54183.729081354344;1392.5683038258173;1431.7923901034292;1435.411481292665;1454.316471258507;1472.6686495538418;27949.580756288266;30061;30136.84931575328;31858.362832743678;31899;45529.54470455385;45555.77222113868;46099.3085103717;46173.53826461827;46185.30722039105;280602;281713;282899;283573;284038;24448.266552003468;26739.197824065308;27886.860565697043;27888.58167127454;27889;39741.60258397379;41156.38265426081;41592.792036039624;48078.759606203144;49637.25544116769;36383;37314.25371492609;39110.217795644494;39488.21023579455;39566.61516846897;38426.23147537089;40467.595324046386;40487.392689110275;40502.392464113655;40829.79585102056;38696.41955370615;39595.80202098971;40840.795796022016;40895.795521022206;40919.181616366914;33792;34172;35082.82458587691;35095.47356789599;35183.12042198965;27499.587506187025;27514.862425687745;27624.30939226455;27928.30179245535;28022.507476695977 06:16:28 INFO - PROCESS | 25095 | |2;jquery.html;390.6132928400882;393.3843534867933;399.5185801109663;400.7755656832174;406.54670042902166;14204.786928196085;16501;18042.819571804277;18613.62772744546;18752.343667971625;15050.548483545484;16817.495475135736;17528.123593820288;17747.55631109223;18528.166232519536;5560.77756889725;5700.914486282709;5708.687001989545;5712.457316554921;5724.370319264877;4057.168280502505;5692.8861422771515;5725.4847063764255;6113.449789518942;6432.5497215194955;6314.968425157867;6347.904781428282;6404.039394090877;6407;6408.487321014308;6132.233470816126;6216.7824126155365;6320.71556779946;6342.238931328224;6349.2698339690605;5592.049351610195;5592.720363981784;5593.412691667357;5598.216249725042;6549.738010479623;25144.87427562869;25813.35466613331;25912.22263332103;25913.22260332193;26491;2602.285804992264;2669.9332516687045;3007.7293043626178;3220.1949512621845;3378.864845406181;15400.922995385063;15414.537563873102;15658.138802365866;15852.76220856688;16304;2063.1850419084376;2163.5023944492855;2206.051397898919;2225.121077174523;2227.7772222777753;88961.55519222427;91108.81119160863;91116;91277.54361228218;93418.53290733503;17133.65732685364;17393.217305221297;17410.47768566945;17549.21028553718;17571.209295581728;30886.845565772026;31009.534856977163;31059;31154.22114447157;31202.68797312043;9651.517424128766;9946.916455898694;9969.202463802878;9981.550830212658;10095.545700443497;7305.159906610807;7970.4819186752675;8042.557659328793;8100.3519718422385;8461.873071903927;7717.459777815611;8470.322374210049;8545.487270763773;8702.912970870337;8713.782155446037;3063.5864158338786;3334.3164651246434;3334.699877011081;3400.285939952638;3538.3454060998797;950.9857352139724;1036.5180191211123;1041.4063983529315;1073.3077165228383;1161.8431511745976;18128.274869005356;18442;18955.336563220222;19000.61998760017;19381.51546211356;1010.9039641234218;1013.1185868294483;1030.160419258302;1048.2662136504478;1065.6536625596711;10424.426656533964;10631.202659800545;10971.177161712903;11050.33697978124;11088.889111108947;471005;476270;478649;509225;509486;85892.57053714691;88819.55590222008;88991;89775.55112224528;90121.54939225262;1022.6778564752079;1051.2693677893944;1053.2995557954027;1069.235496619905;1076.1229597877705;16493.505194844172;16660.58348541296;17045.659086818443;17130.914345428195;17590.73613895767;37354;37762.43356349712;38333.4249986256;38587.421188681634;38755;48944.510554894;53904.730476347366;55525.44474555203;55879.44120558905;56103;8550.57247137653;8714.128587141235;8831.735047948572;8864.069272726529;8908.242799361868;9662.468564229035;13039.152455090389;13393.66515837112;13475.797863032258;13785.241811700453;10717.9464102679;11807.409629518317;11935.283882967049;12036.879631203576;12121.69695757613;11314;11387.0321022714;11499.885001150216;11652.94173529127;11678.299302041905;11828.172027958304;11948.641540753792;11953;12069.57756478519;12218.505018225325;8174.836503269782;8189.201195984547;8195.057568379712;8253.628586713732;9125.498097604695;8219;10372.377657340583;11462.713432164262;11724.941375293069;11923.940380298043 06:16:28 INFO - PROCESS | 25095 | |3;mootools.html;1344.6167842164984;1469.9118052916822;1536.0630015690429;1650.3064427724355;1800.972985405219;7890.329322007629;8167.714130005451;8193.631286592103;8212.712555060574;8222.835543289131;8011.5193088414735;8161.510309381427;8166.265036146746;8175;8176.918230817705;1951.9121639526184;2181.291080398869;2200.559888022398;2225.4658881868318;2269.1377276634857;2294.678744975696;2314.907403703858;2317.0152685108847;2317.9536409271805;2322.7677232276724;2219.2454565447742;2268.534950335177;2269.693591365169;2270.182734215681;2270.9772902271015;1944.1542928826007;2171.250918433138;2214.933551993443;2238.742544607375;2242.7869352411494;2179.030331502466;2191.824654027674;2204.4048107011176;2205.106931692651;2218.2457964292216;7907.44647874649;7999.080105787851;8008.199180082003;8009.439339246255;8024.919750802534;4780;4936.753162341868;4943.480934501861;4948.653594248404;5007.974960125176;3286.572745543088;3312.9834350828332;3352.2625022495013;3400.489926511043;3405.4721518164724;1032.7418145463635;1040.7398150462384;1049.58541376156;1054.0671505717419;1088.6244245735209;11173.329600224013;11207;11211.047060999825;11223.494942727597;11271.32372057671;4167.74993500391;4170.207660544487;4323.459567554056;4331.761753103609;4345.782710864444;9515.524223788783;9519.286053545873;9523.857142142873;9559.761005974906;9560.043995600385;4284.700070995001;4959.380077490314;4960.404751429852;4973.577245934064;4986.501349864984;4502.954970450253;4624.722516649012;4628.5371462853445;4650.023495066006;4669.929951050737;3681.9263614728093;3699.667029967316;3765.2281282337053;3797.4493698413576;3800.1639639279315;1151.1826603111883;1195.8266051422495;1209.8971587414992;1217.7077501399601;1219.951201951912;900.4417261298037;1042.95828166874;1049.5539395756773;1054.5307338234566;1063.1335461598771;5895.263092113486;5932.911006334908;5942.64344139353;5984.401559843981;6173.69131543421;913.584319134792;917.2157805076563;953.413650604873;1006.2050979726081;1007.1892126837965;4134.235166494148;4199.160167966358;4216.64158546527;4225.260579398654;4233.301505251599;9944.204463642891;10362.481875906175;10369;10385.2211084169;10396.896031039743;10195.898041019644;10214.642487512901;10239.539220734936;10248.6412975547;10253.282270241012;7624.58064806441;7812.414068944852;7818;7863.13505514393;7870.488418252795;5618.297712785902;5731.5128214100605;5830.708464576839;5874.118882167709;5955.493783028491;9364.95317523408;9394.577244023898;9490.145886870216;9516.143547080796;9523.857142143012;9176.908230917605;9394.765130871783;9690.660826871026;9701.805963880543;9784.608615655294;6133.877322453437;6687.899681504684;6873.243943166148;7033.859322813618;7154.463415243774;8070.071941726542;8086.1913808618665;8139.959300203461;8176.936998190436;8208.302294304813;7052.153741551047;7216.097987751531;7243.732346839177;7323.487355885037;7372.262773722585;6996.545224560387;7078.787636370917;7082.220955694766;7175.246599107228;7242.782716518513;6439.420452159328;6581.638009909501;6611.570247933869;6622.139121914121;6639.834004149935;4789.880252993703;4814.711117332971;4833.299171620025;4835.709857408567;4920.876978075434;5013.548780609763;5017.648764586444;5052.545270925634;5064.924026139537;5081.18701007837 06:16:28 INFO - PROCESS | 25095 | |4;prototype.html;322.9499427588723;328.81750628401244;329.47119872604486;338.0972802617013;339.1944132684873;5547.805826796058;5640.323161220649;5641.125625528045;5642.746076426561;5682.971585142069;5529.3364796224405;5533.205731105796;5558.610897237195;5565.972170139154;5571.164325351199;4122.505299364073;4212.894677633069;4398.3842262083335;4403.845865394712;4409.4488188976375;4356.956430435703;4372.366006928994;4375.7593332366705;4382.948092457819;4395.252807022814;4131.690123240754;4336.891577710567;4343.348497725351;4344.956550434486;4358.8474403395885;4230.894227644319;4353.867994321467;4366.97816510917;4369.880607905164;4381.145676593065;3850.7497012694084;3864.246471937973;3876.8061596920325;3876.8836934891997;3887.630675085862;5300.204969254604;5356.196570514415;5415.0523658360025;5415.296011518517;5420.566354691615;5001.3998320201445;5022.84931452057;5029.591714319996;5044.192929131358;5057.69653820772;5786.421357864222;5834.883302333972;5836;5838.9124163137585;5883.499902508292;3913.882583522499;4260.7656578888145;4272.487301523837;4278.572142785728;4287.099709061101;6075.574709770318;6092.5430592705725;6123.663198524079;6129.21120889994;6154.199954005995;4144.254034273799;4151.965536886964;4170.791460426966;4177.373393990925;4180.163967206571;4084.754149984224;4213.578642135762;4222.353282219937;4232.915341693149;4236.745795252295;4856.757162141878;4867.951320486821;4889.804407823718;4910.386201724785;4912.6561140719805;5865.4134586541;5900.468957793819;5901.049475262369;5953.880922381528;5979.641221526722;1461.4665647038967;1482.9925850370894;1484.3765618440282;1500.4673340964018;1505.9698806024037;920.8342498350228;969.99030009699;985.4678473624163;998.5406712911996;1001.519270750044;5587.608867379245;5630.887382252414;5633.521150702154;5660.29246344207;5668.093105103164;6006;6006.429389208019;6023.457888789942;6025.578209525291;6026.337102918676;5479.835604931858;5687.630304030307;5805.36141024487;5807.767689292466;5807.82576522705;3257.446234140202;3282.409166350032;3283.835808209628;3287.3096649703352;3290.68738469845;6205.410486003822;6299.622022678563;6321.683915804283;6329.398707122816;6347.55567110307;6249.406306400884;6278.591891527036;6281.780137695158;6287.1512345832725;6293.402126797947;6111.174991376108;6321.589096708699;6327.766085613353;6328.905066424008;6340.144080549159;6097.5731698780655;6121.265448146251;6129.908051379322;6144.631322120687;6146;6007.879842403215;6011.969940150271;6038.879222415615;6040.335563088145;6043.848903777441;6033.456988871023;6238.656873871981;6283;6289.308176100534;6295.048534954179;4193.056562273464;4204.873853784391;4205.600467955539;4209;4210.3369169177395;5969.283685957713;6104.4200800923845;6126.6017708848785;6128.387161283836;6130.969345153246;5887.911681324869;5921.407859214217;5922.851928701644;5924.31870334917;5936.910946335895;5838.970805145947;5883.0881213411985;5896.705164741831;5906.143609176731;5906.320773111147;5402.189671549142;5644.463775941444;5805.477507024388;5808.796692115756;5841.007028805028;5962.25471816023;5964.254468191476;5977.6114552553945;5988.101784732151;5995;5648.745806438638;5830.300363956352;5836.445537674084;5845.269341332333;5846.94153058464 06:16:28 INFO - PROCESS | 25095 | |5;yui.html;753.2229728057805;757.3335464790987;807.5154907055771;814.4054839966824;819.872919697447;14688.339024743886;14998.550043498684;15085.472008479705;15097.320620572073;15098.245087745596;14348.497802576912;14429.5671129866;14479.058861174017;14481;14509.782353264709;697.5779653309751;714.0895358418006;716.6524235745659;846.3229416466818;860.1484530315006;711.1466240511381;819.9877001844977;852.526847599582;854.1714536899229;855.1064137975799;864.5331520978664;868.1231955724701;868.3487384461654;868.9652413903468;872.0494660819705;734.3024127079296;861.8060936289316;868.7524055644175;870.9869351959726;871.9607617657189;631.1763149090435;636.805774238857;649.302000349626;649.506375154881;649.5550547874674;13951.093178943438;13967.022308438414;14025.64935876601;14074.507392241223;14145.929270353685;14343.928280358636;14774.965752397336;14786.704265914621;14858.777118343234;15005.24973751319;10765.246432749711;10793.78412431747;11982.101342399268;12060.939695301555;12105.273683578926;3990.521137463523;4008.3834174813;4066.63400293972;4073.429719839225;4149.2738770715005;55384;57193.42806571923;57207.427925721044;57237;58956.11565826516;11676;11816.113791465674;11938.164328496921;11941.16411851162;12256.14207005501;20932.686009709865;21004.159833606485;21147.154113835273;21166.78833211648;21175.894120529298;8675.175858293453;8809;8886.777830554287;8989.730308090768;9104.453732776055;4743.762811859393;4765.523447655276;4975.875603109951;5019.949800502021;5072.137736584789;3260.8858689946214;3328.21786880083;3340.1148695595684;3344.2809795894;3345.6487068857905;577.4831525784483;579.9217105690763;588.0708480600641;588.2999230915283;595.6098755315276;602.421675191812;606.7876243314893;607.9756809727561;716.5449939288485;741.8405042915799;8912.287017038621;9140.451572905647;9162.358634895627;9238.260939124853;9389.765255868659;686.3445409633788;756.6216891554222;759.9848003039908;773.5899973014311;788.5268838696851;3932.4297976793205;3940.4483372327322;4010.3583426651594;4020.1254859847377;4076;52848.47151528512;55034.72482637561;63877.68061159757;64248.67875660592;64290.357096429376;49691.50308496869;51074;51359.22961155586;51418.228726569134;51830.48169518256;45355.319670204975;45649;46858.29712554315;47240.76379618148;47337.763311183226;9167.854018247719;9812.165965892978;9854.655087071918;9916.405015699082;9982.550785214538;33300.33399331952;33366.332673346886;33957.151071223416;34004.319913602085;34963.65036349706;41898.79050604728;44018.559814401444;44494.55505444904;44566.554334457534;45153.774231128635;12392.75214495723;12477.251364918135;12511.311877846805;12790;12852.164609300366;14031.929840350733;14124.43502259898;14339.211343376215;14733.852661473247;14895;13031.869681303446;13071.934640326737;13258.734825303247;13343.132696375093;13674;12215.57245496439;12284.447199876206;12305.56930507428;12537.937310313755;12577.559785407468;12691.93654031724;12854.742905141658;13537.187768733907;13577.32113394346;13689.452421903012;10725.839112413476;10984.670459886216;11145;11273.436328183721;11332.830007549728;10912.181586380895;10918.23572349928;11192.552297908318;11245.269057511236;11267.26762760418 06:16:28 INFO - PROCESS | 25095 | __end_tp_report 06:16:28 INFO - PROCESS | 25095 | __start_cc_report 06:16:28 INFO - PROCESS | 25095 | _x_x_mozilla_cycle_collect,916 06:16:28 INFO - PROCESS | 25095 | __end_cc_report 06:16:28 INFO - PROCESS | 25095 | __startTimestamp1461330988704__endTimestamp 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | ------- Summary: start ------- 06:16:28 INFO - PROCESS | 25095 | Number of tests: 6 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | [#0] dojo.html Cycles:165 Average:5187.47 Median:5393.38 stddev:569.33 (10.6%) stddev-sans-first:545.75 06:16:28 INFO - PROCESS | 25095 | Values: 3047.0 3103.9 3134.1 3134.9 3169.3 5062.5 5127.4 5191.6 5192.2 5200.6 5250.8 5261.4 5348.7 5354.4 5367.7 4722.7 4738.1 4761.3 4762.8 4944.0 4930.7 4932.6 4934.5 4942.7 4956.8 4594.5 4663.9 4685.9 4851.5 4911.5 4736.1 4737.9 4771.7 4784.2 4848.9 4726.6 4750.7 4764.9 4778.8 4802.6 4931.2 5117.8 5156.7 5163.8 5199.0 5535.8 5538.3 5538.4 5577.7 5588.6 5563.0 5566.6 5586.6 5589.5 5599.6 5071.2 5185.5 5219.1 5229.1 5230.0 5679.5 5680.7 5684.3 5686.5 5707.9 5443.0 5448.8 5453.8 5456.5 5460.8 5426.8 5504.0 5526.0 5584.4 5586.1 5551.8 5552.8 5553.3 5554.3 5556.1 5541.5 5548.9 5549.0 5549.2 5598.7 4125.5 4230.6 4274.9 4311.8 4318.9 3790.2 3811.5 3817.4 3830.8 3853.6 5170.9 5269.7 5279.7 5283.8 5313.5 5572.8 5651.1 5656.0 5666.4 5707.7 5269.8 5304.5 5309.9 5322.1 5322.7 4701.5 4808.0 4821.6 4822.8 4864.6 5773.0 5799.9 5801.1 5801.5 5850.4 5597.4 5610.5 5610.9 5614.6 5616.2 5664.9 5730.1 5733.7 5785.9 5789.1 5450.1 5457.2 5459.6 5463.2 5504.7 5515.2 5519.9 5520.1 5521.4 5555.4 5359.3 5363.2 5386.4 5422.4 5473.3 5471.9 5473.7 5475.3 5476.3 5481.2 5347.0 5393.4 5393.5 5394.6 5442.9 5283.3 5319.4 5514.6 5559.2 5596.6 5316.5 5408.7 5413.1 5414.8 5467.3 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | [#1] ext.html Cycles:180 Average:47488.92 Median:38561.33 stddev:79218.80 (205.4%) stddev-sans-first:79362.84 06:16:28 INFO - PROCESS | 25095 | Values: 610.9 646.1 653.0 657.4 658.0 36920.6 37935.0 37959.2 37984.0 38007.2 40375.0 41214.6 41245.0 41270.4 41299.0 8548.3 8781.7 8803.2 8810.4 8815.4 8670.1 8800.6 8802.7 8804.6 8806.0 8761.2 8810.7 8851.7 8857.7 8865.2 8730.5 8790.0 8801.1 8801.5 8806.0 8710.8 8825.7 8826.9 8828.2 8830.3 41069.6 41596.8 41620.6 41693.8 41745.4 3614.3 3712.6 3758.5 3780.8 4038.9 31948.0 32477.4 32488.5 32506.2 32636.3 4104.5 4153.2 4196.0 4225.8 4228.6 278756.0 278958.0 280613.0 280694.0 281395.6 54646.5 55449.3 55512.7 55582.0 55593.7 53054.7 61765.0 62981.3 63062.7 63146.0 16370.8 16489.0 16667.5 16670.7 16707.6 10620.4 12888.9 12958.6 12964.9 12975.9 15596.4 15816.4 15910.2 15952.6 16152.7 6444.0 6529.4 6613.3 6641.8 6747.3 989.8 1017.9 1036.4 1090.8 1207.5 30282.4 30484.2 30638.5 32719.8 33099.2 1119.5 1213.6 1214.4 1232.9 1244.5 15365.3 15594.5 15617.8 15761.4 15836.7 338342.0 342304.0 343403.0 344516.0 344607.0 50305.5 50889.2 50899.5 54131.2 54183.7 1392.6 1431.8 1435.4 1454.3 1472.7 27949.6 30061.0 30136.8 31858.4 31899.0 45529.5 45555.8 46099.3 46173.5 46185.3 280602.0 281713.0 282899.0 283573.0 284038.0 24448.3 26739.2 27886.9 27888.6 27889.0 39741.6 41156.4 41592.8 48078.8 49637.3 36383.0 37314.3 39110.2 39488.2 39566.6 38426.2 40467.6 40487.4 40502.4 40829.8 38696.4 39595.8 40840.8 40895.8 40919.2 33792.0 34172.0 35082.8 35095.5 35183.1 27499.6 27514.9 27624.3 27928.3 28022.5 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | [#2] jquery.html Cycles:180 Average:29887.58 Median:31178.45 stddev:80600.31 (258.5%) stddev-sans-first:80795.99 06:16:28 INFO - PROCESS | 25095 | Values: 390.6 393.4 399.5 400.8 406.5 14204.8 16501.0 18042.8 18613.6 18752.3 15050.5 16817.5 17528.1 17747.6 18528.2 5560.8 5700.9 5708.7 5712.5 5724.4 4057.2 5692.9 5725.5 6113.4 6432.5 6315.0 6347.9 6404.0 6407.0 6408.5 6132.2 6216.8 6320.7 6342.2 6349.3 5592.0 5592.7 5593.4 5598.2 6549.7 25144.9 25813.4 25912.2 25913.2 26491.0 2602.3 2669.9 3007.7 3220.2 3378.9 15400.9 15414.5 15658.1 15852.8 16304.0 2063.2 2163.5 2206.1 2225.1 2227.8 88961.6 91108.8 91116.0 91277.5 93418.5 17133.7 17393.2 17410.5 17549.2 17571.2 30886.8 31009.5 31059.0 31154.2 31202.7 9651.5 9946.9 9969.2 9981.6 10095.5 7305.2 7970.5 8042.6 8100.4 8461.9 7717.5 8470.3 8545.5 8702.9 8713.8 3063.6 3334.3 3334.7 3400.3 3538.3 951.0 1036.5 1041.4 1073.3 1161.8 18128.3 18442.0 18955.3 19000.6 19381.5 1010.9 1013.1 1030.2 1048.3 1065.7 10424.4 10631.2 10971.2 11050.3 11088.9 471005.0 476270.0 478649.0 509225.0 509486.0 85892.6 88819.6 88991.0 89775.6 90121.5 1022.7 1051.3 1053.3 1069.2 1076.1 16493.5 16660.6 17045.7 17130.9 17590.7 37354.0 37762.4 38333.4 38587.4 38755.0 48944.5 53904.7 55525.4 55879.4 56103.0 8550.6 8714.1 8831.7 8864.1 8908.2 9662.5 13039.2 13393.7 13475.8 13785.2 10717.9 11807.4 11935.3 12036.9 12121.7 11314.0 11387.0 11499.9 11652.9 11678.3 11828.2 11948.6 11953.0 12069.6 12218.5 8174.8 8189.2 8195.1 8253.6 9125.5 8219.0 10372.4 11462.7 11724.9 11923.9 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | [#3] mootools.html Cycles:180 Average:5463.99 Median:4878.29 stddev:3032.61 (62.2%) stddev-sans-first:3025.31 06:16:28 INFO - PROCESS | 25095 | Values: 1344.6 1469.9 1536.1 1650.3 1801.0 7890.3 8167.7 8193.6 8212.7 8222.8 8011.5 8161.5 8166.3 8175.0 8176.9 1951.9 2181.3 2200.6 2225.5 2269.1 2294.7 2314.9 2317.0 2318.0 2322.8 2219.2 2268.5 2269.7 2270.2 2271.0 1944.2 2171.3 2214.9 2238.7 2242.8 2179.0 2191.8 2204.4 2205.1 2218.2 7907.4 7999.1 8008.2 8009.4 8024.9 4780.0 4936.8 4943.5 4948.7 5008.0 3286.6 3313.0 3352.3 3400.5 3405.5 1032.7 1040.7 1049.6 1054.1 1088.6 11173.3 11207.0 11211.0 11223.5 11271.3 4167.7 4170.2 4323.5 4331.8 4345.8 9515.5 9519.3 9523.9 9559.8 9560.0 4284.7 4959.4 4960.4 4973.6 4986.5 4503.0 4624.7 4628.5 4650.0 4669.9 3681.9 3699.7 3765.2 3797.4 3800.2 1151.2 1195.8 1209.9 1217.7 1220.0 900.4 1043.0 1049.6 1054.5 1063.1 5895.3 5932.9 5942.6 5984.4 6173.7 913.6 917.2 953.4 1006.2 1007.2 4134.2 4199.2 4216.6 4225.3 4233.3 9944.2 10362.5 10369.0 10385.2 10396.9 10195.9 10214.6 10239.5 10248.6 10253.3 7624.6 7812.4 7818.0 7863.1 7870.5 5618.3 5731.5 5830.7 5874.1 5955.5 9365.0 9394.6 9490.1 9516.1 9523.9 9176.9 9394.8 9690.7 9701.8 9784.6 6133.9 6687.9 6873.2 7033.9 7154.5 8070.1 8086.2 8140.0 8176.9 8208.3 7052.2 7216.1 7243.7 7323.5 7372.3 6996.5 7078.8 7082.2 7175.2 7242.8 6439.4 6581.6 6611.6 6622.1 6639.8 4789.9 4814.7 4833.3 4835.7 4920.9 5013.5 5017.6 5052.5 5064.9 5081.2 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | [#4] prototype.html Cycles:180 Average:4955.44 Median:5646.60 stddev:1479.42 (26.2%) stddev-sans-first:1442.13 06:16:28 INFO - PROCESS | 25095 | Values: 322.9 328.8 329.5 338.1 339.2 5547.8 5640.3 5641.1 5642.7 5683.0 5529.3 5533.2 5558.6 5566.0 5571.2 4122.5 4212.9 4398.4 4403.8 4409.4 4357.0 4372.4 4375.8 4382.9 4395.3 4131.7 4336.9 4343.3 4345.0 4358.8 4230.9 4353.9 4367.0 4369.9 4381.1 3850.7 3864.2 3876.8 3876.9 3887.6 5300.2 5356.2 5415.1 5415.3 5420.6 5001.4 5022.8 5029.6 5044.2 5057.7 5786.4 5834.9 5836.0 5838.9 5883.5 3913.9 4260.8 4272.5 4278.6 4287.1 6075.6 6092.5 6123.7 6129.2 6154.2 4144.3 4152.0 4170.8 4177.4 4180.2 4084.8 4213.6 4222.4 4232.9 4236.7 4856.8 4868.0 4889.8 4910.4 4912.7 5865.4 5900.5 5901.0 5953.9 5979.6 1461.5 1483.0 1484.4 1500.5 1506.0 920.8 970.0 985.5 998.5 1001.5 5587.6 5630.9 5633.5 5660.3 5668.1 6006.0 6006.4 6023.5 6025.6 6026.3 5479.8 5687.6 5805.4 5807.8 5807.8 3257.4 3282.4 3283.8 3287.3 3290.7 6205.4 6299.6 6321.7 6329.4 6347.6 6249.4 6278.6 6281.8 6287.2 6293.4 6111.2 6321.6 6327.8 6328.9 6340.1 6097.6 6121.3 6129.9 6144.6 6146.0 6007.9 6012.0 6038.9 6040.3 6043.8 6033.5 6238.7 6283.0 6289.3 6295.0 4193.1 4204.9 4205.6 4209.0 4210.3 5969.3 6104.4 6126.6 6128.4 6131.0 5887.9 5921.4 5922.9 5924.3 5936.9 5839.0 5883.1 5896.7 5906.1 5906.3 5402.2 5644.5 5805.5 5808.8 5841.0 5962.3 5964.3 5977.6 5988.1 5995.0 5648.7 5830.3 5836.4 5845.3 5846.9 06:16:28 INFO - PROCESS | 25095 | 06:16:28 INFO - PROCESS | 25095 | [#5] yui.html Cycles:180 Average:14848.04 Median:42958.68 stddev:16586.74 (38.6%) stddev-sans-first:16599.50 06:16:28 INFO - PROCESS | 25095 | Values: 753.2 757.3 807.5 814.4 819.9 14688.3 14998.6 15085.5 15097.3 15098.2 14348.5 14429.6 14479.1 14481.0 14509.8 697.6 714.1 716.7 846.3 860.1 711.1 820.0 852.5 854.2 855.1 864.5 868.1 868.3 869.0 872.0 734.3 861.8 868.8 871.0 872.0 631.2 636.8 649.3 649.5 649.6 13951.1 13967.0 14025.6 14074.5 14145.9 14343.9 14775.0 14786.7 14858.8 15005.2 10765.2 10793.8 11982.1 12060.9 12105.3 3990.5 4008.4 4066.6 4073.4 4149.3 55384.0 57193.4 57207.4 57237.0 58956.1 11676.0 11816.1 11938.2 11941.2 12256.1 20932.7 21004.2 21147.2 21166.8 21175.9 8675.2 8809.0 8886.8 8989.7 9104.5 4743.8 4765.5 4975.9 5019.9 5072.1 3260.9 3328.2 3340.1 3344.3 3345.6 577.5 579.9 588.1 588.3 595.6 602.4 606.8 608.0 716.5 741.8 8912.3 9140.5 9162.4 9238.3 9389.8 686.3 756.6 760.0 773.6 788.5 3932.4 3940.4 4010.4 4020.1 4076.0 52848.5 55034.7 63877.7 64248.7 64290.4 49691.5 51074.0 51359.2 51418.2 51830.5 45355.3 45649.0 46858.3 47240.8 47337.8 9167.9 9812.2 9854.7 9916.4 9982.6 33300.3 33366.3 33957.2 34004.3 34963.7 41898.8 44018.6 44494.6 44566.6 45153.8 12392.8 12477.3 12511.3 12790.0 12852.2 14031.9 14124.4 14339.2 14733.9 14895.0 13031.9 13071.9 13258.7 13343.1 13674.0 12215.6 12284.4 12305.6 12537.9 12577.6 12691.9 12854.7 13537.2 13577.3 13689.5 10725.8 10984.7 11145.0 11273.4 11332.8 10912.2 10918.2 11192.6 11245.3 11267.3 06:16:28 INFO - PROCESS | 25095 | -------- Summary: end -------- 06:16:28 INFO - PROCESS | 25095 | 06:16:29 INFO - TEST-INFO | 25095: exit 0 06:16:29 INFO - TEST-OK | dromaeo_css | took 1111319ms 06:16:29 INFO - TEST-START | kraken 06:16:29 INFO - operating with platform_type : linux_ 06:16:29 INFO - Initialising browser for kraken test... 06:16:29 INFO - TEST-INFO | started process 25274 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfXDxwf/profile http://localhost:59955/getInfo.html) 06:16:32 INFO - PROCESS | 25274 | __metrics Screen width/height:1600/1200 06:16:32 INFO - PROCESS | 25274 | colorDepth:24 06:16:32 INFO - PROCESS | 25274 | Browser inner width/height: 1024/697 06:16:32 INFO - PROCESS | 25274 | __metrics 06:16:33 ERROR - PROCESS | 25274 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:16:34 INFO - TEST-INFO | 25274: exit 0 06:16:34 INFO - Browser initialized. 06:16:34 INFO - Running cycle 1/1 for kraken test... 06:16:34 INFO - TEST-INFO | started process 25357 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfXDxwf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:46 INFO - PROCESS | 25357 | RSS: Main: 153829376 06:16:46 INFO - PROCESS | 25357 | 06:16:48 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59955/tests/kraken/driver.html?testName=audio-beat-detection) 06:16:49 INFO - PROCESS | 25357 | RSS: Main: 208519168 06:16:49 INFO - PROCESS | 25357 | 06:16:52 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59955/tests/kraken/driver.html?testName=audio-dft) 06:16:52 INFO - PROCESS | 25357 | RSS: Main: 194928640 06:16:52 INFO - PROCESS | 25357 | 06:16:56 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59955/tests/kraken/driver.html?testName=audio-fft) 06:16:56 INFO - PROCESS | 25357 | RSS: Main: 265654272 06:16:56 INFO - PROCESS | 25357 | 06:16:58 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59955/tests/kraken/driver.html?testName=audio-oscillator) 06:16:59 INFO - PROCESS | 25357 | RSS: Main: 172601344 06:16:59 INFO - PROCESS | 25357 | 06:17:01 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59955/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:02 INFO - PROCESS | 25357 | RSS: Main: 177541120 06:17:02 INFO - PROCESS | 25357 | 06:17:05 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59955/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:05 INFO - PROCESS | 25357 | RSS: Main: 214003712 06:17:05 INFO - PROCESS | 25357 | 06:17:09 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59955/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:09 INFO - PROCESS | 25357 | RSS: Main: 214503424 06:17:09 INFO - PROCESS | 25357 | 06:17:12 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59955/tests/kraken/driver.html?testName=json-parse-financial) 06:17:13 INFO - PROCESS | 25357 | RSS: Main: 214315008 06:17:13 INFO - PROCESS | 25357 | 06:17:14 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59955/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:15 INFO - PROCESS | 25357 | RSS: Main: 201912320 06:17:15 INFO - PROCESS | 25357 | 06:17:17 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:17 INFO - PROCESS | 25357 | RSS: Main: 203108352 06:17:17 INFO - PROCESS | 25357 | 06:17:19 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:20 INFO - PROCESS | 25357 | RSS: Main: 219217920 06:17:20 INFO - PROCESS | 25357 | 06:17:22 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:17:23 INFO - PROCESS | 25357 | RSS: Main: 223850496 06:17:23 INFO - PROCESS | 25357 | 06:17:26 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:26 INFO - PROCESS | 25357 | RSS: Main: 219164672 06:17:26 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | Cycle 1(1): loaded http://localhost:59955/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59955/tests/kraken/driver.html?testName=ai-astar) 06:17:28 INFO - PROCESS | 25357 | RSS: Main: 283029504 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | __start_tp_report 06:17:28 INFO - PROCESS | 25357 | _x_x_mozilla_page_load 06:17:28 INFO - PROCESS | 25357 | _x_x_mozilla_page_load_details 06:17:28 INFO - PROCESS | 25357 | |i|pagename|runs| 06:17:28 INFO - PROCESS | 25357 | |0;ai-astar;110;111;104;110;111;111;101;109;111;110 06:17:28 INFO - PROCESS | 25357 | |1;audio-beat-detection;155;127;154;178;136;155;159;129;187;137 06:17:28 INFO - PROCESS | 25357 | |2;audio-dft;162;160;160;160;160;160;161;161;161;161 06:17:28 INFO - PROCESS | 25357 | |3;audio-fft;73;73;102;114;72;75;71;100;103;76 06:17:28 INFO - PROCESS | 25357 | |4;audio-oscillator;92;93;153;92;93;93;139;94;93;151 06:17:28 INFO - PROCESS | 25357 | |5;imaging-gaussian-blur;116;116;116;118;117;117;117;117;117;127 06:17:28 INFO - PROCESS | 25357 | |6;imaging-darkroom;145;147;145;146;145;145;146;146;145;146 06:17:28 INFO - PROCESS | 25357 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 06:17:28 INFO - PROCESS | 25357 | |8;json-parse-financial;59;59;57;56;59;57;56;77;56;55 06:17:28 INFO - PROCESS | 25357 | |9;json-stringify-tinderbox;60;62;60;61;61;60;61;60;61;62 06:17:28 INFO - PROCESS | 25357 | |10;stanford-crypto-aes;78;85;76;77;77;79;87;78;78;79 06:17:28 INFO - PROCESS | 25357 | |11;stanford-crypto-ccm;130;131;126;124;124;126;124;125;156;125 06:17:28 INFO - PROCESS | 25357 | |12;stanford-crypto-pbkdf2;171;172;164;165;167;174;172;164;164;176 06:17:28 INFO - PROCESS | 25357 | |13;stanford-crypto-sha256-iterative;67;69;69;68;67;68;67;69;68;68 06:17:28 INFO - PROCESS | 25357 | __end_tp_report 06:17:28 INFO - PROCESS | 25357 | __start_cc_report 06:17:28 INFO - PROCESS | 25357 | _x_x_mozilla_cycle_collect,1614 06:17:28 INFO - PROCESS | 25357 | __end_cc_report 06:17:28 INFO - PROCESS | 25357 | __startTimestamp1461331048867__endTimestamp 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | ------- Summary: start ------- 06:17:28 INFO - PROCESS | 25357 | Number of tests: 14 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.50 stddev:3.46 (3.1%) stddev-sans-first:3.64 06:17:28 INFO - PROCESS | 25357 | Values: 110.0 111.0 104.0 110.0 111.0 111.0 101.0 109.0 111.0 110.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#1] audio-beat-detection Cycles:10 Average:151.70 Median:155.00 stddev:20.02 (12.9%) stddev-sans-first:21.20 06:17:28 INFO - PROCESS | 25357 | Values: 155.0 127.0 154.0 178.0 136.0 155.0 159.0 129.0 187.0 137.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.53 06:17:28 INFO - PROCESS | 25357 | Values: 162.0 160.0 160.0 160.0 160.0 160.0 161.0 161.0 161.0 161.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#3] audio-fft Cycles:10 Average:85.90 Median:72.50 stddev:16.68 (23.0%) stddev-sans-first:17.03 06:17:28 INFO - PROCESS | 25357 | Values: 73.0 73.0 102.0 114.0 72.0 75.0 71.0 100.0 103.0 76.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#4] audio-oscillator Cycles:10 Average:109.30 Median:93.00 stddev:26.72 (28.7%) stddev-sans-first:27.60 06:17:28 INFO - PROCESS | 25357 | Values: 92.0 93.0 153.0 92.0 93.0 93.0 139.0 94.0 93.0 151.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:117.00 stddev:3.29 (2.8%) stddev-sans-first:3.43 06:17:28 INFO - PROCESS | 25357 | Values: 116.0 116.0 116.0 118.0 117.0 117.0 117.0 117.0 117.0 127.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 06:17:28 INFO - PROCESS | 25357 | Values: 145.0 147.0 145.0 146.0 145.0 145.0 146.0 146.0 145.0 146.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 06:17:28 INFO - PROCESS | 25357 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:58.00 stddev:6.45 (11.1%) stddev-sans-first:6.85 06:17:28 INFO - PROCESS | 25357 | Values: 59.0 59.0 57.0 56.0 59.0 57.0 56.0 77.0 56.0 55.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.79 (1.3%) stddev-sans-first:0.78 06:17:28 INFO - PROCESS | 25357 | Values: 60.0 62.0 60.0 61.0 61.0 60.0 61.0 60.0 61.0 62.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:78.50 stddev:3.63 (4.6%) stddev-sans-first:3.81 06:17:28 INFO - PROCESS | 25357 | Values: 78.0 85.0 76.0 77.0 77.0 79.0 87.0 78.0 78.0 79.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:126.00 stddev:9.77 (7.8%) stddev-sans-first:10.36 06:17:28 INFO - PROCESS | 25357 | Values: 130.0 131.0 126.0 124.0 124.0 126.0 124.0 125.0 156.0 125.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:171.50 stddev:4.61 (2.7%) stddev-sans-first:4.82 06:17:28 INFO - PROCESS | 25357 | Values: 171.0 172.0 164.0 165.0 167.0 174.0 172.0 164.0 164.0 176.0 06:17:28 INFO - PROCESS | 25357 | 06:17:28 INFO - PROCESS | 25357 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 06:17:28 INFO - PROCESS | 25357 | Values: 67.0 69.0 69.0 68.0 67.0 68.0 67.0 69.0 68.0 68.0 06:17:28 INFO - PROCESS | 25357 | -------- Summary: end -------- 06:17:28 INFO - PROCESS | 25357 | 06:17:29 INFO - TEST-INFO | 25357: exit 0 06:17:29 INFO - TEST-OK | kraken | took 60413ms 06:17:29 INFO - SUITE-END | took 1171s 06:17:30 INFO - Completed test suite (00:19:31) 06:17:30 INFO - javascript benchmark 06:17:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3046.9999999999995, 3103.8913638022673, 3134.1224457151993, 3134.9216269593257, 3169.2869104451493, 5062.519060689237, 5127.384713834345, 5191.636585439021, 5192.247124166998, 5200.6099542534275, 5250.789968401259, 5261.368635763704, 5348.652337598053, 5354.437784032669, 5367.677939323643, 4722.7402492862875, 4738.076075165344, 4761.262004389325, 4762.78567464465, 4944.035912996967, 4930.654854160211, 4932.630052746041, 4934.481879402647, 4942.703437793726, 4956.752162391884, 4594.47163576188, 4663.860084197479, 4685.929711054337, 4851.490593487686, 4911.45973942866, 4736.100140973204, 4737.857864264043, 4771.6898401603785, 4784.186688263004, 4848.927266091011, 4726.598239149676, 4750.71495710255, 4764.880877978044, 4778.784954677013, 4802.59177969873, 4931.211006238985, 5117.8464646060675, 5156.690598564098, 5163.8192663256605, 5199.0, 5535.778568857241, 5538.335399752055, 5538.418466061086, 5577.665340079568, 5588.608797384144, 5562.9986602411545, 5566.582506312043, 5586.608937374385, 5589.469000444951, 5599.636023658449, 5071.1632580624155, 5185.481451854822, 5219.086659834514, 5229.08491014071, 5229.973850130801, 5679.488846003879, 5680.715964201773, 5684.261046064068, 5686.545076393879, 5707.857303567444, 5442.972785136049, 5448.83653490396, 5453.836384908459, 5456.454354564591, 5460.836174914759, 5426.782928682887, 5503.972480137573, 5526.005319042531, 5584.441555844383, 5586.050371436865, 5551.805686800943, 5552.833414997556, 5553.278073850373, 5554.250176226158, 5556.111022236423, 5541.5289700375915, 5548.861278468071, 5548.9722551386985, 5549.223108764776, 5598.692071936001, 4125.484314460692, 4230.555791641895, 4274.914501710011, 4311.805968731414, 4318.935215971763, 3790.166163444093, 3811.5426148862316, 3817.370133927871, 3830.80845957701, 3853.5953724858487, 5170.8965820684125, 5269.710165940834, 5279.656822306538, 5283.815066472655, 5313.468653134656, 5572.804951826666, 5651.124075768263, 5656.0, 5666.433356664301, 5707.6575405475805, 5269.78920843162, 5304.496072873071, 5309.867253318698, 5322.0952438085615, 5322.707251101149, 4701.459332176775, 4808.0, 4821.63837712166, 4822.758862056883, 4864.5621894029, 5772.971135144381, 5799.855003624859, 5801.129830525455, 5801.53587712982, 5850.4149585042005, 5597.384287728428, 5610.523105535912, 5610.85972850682, 5614.63504872182, 5616.185653080199, 5664.943350566442, 5730.054541000855, 5733.655980641175, 5785.884282314246, 5789.073748200268, 5450.1007333789485, 5457.154141108134, 5459.5632349412745, 5463.235147079333, 5504.669719816824, 5515.172724091258, 5519.917201241904, 5520.06158952971, 5521.4478552144465, 5555.444455554412, 5359.3032905722, 5363.19552067193, 5386.407495175449, 5422.376426710979, 5473.261109750133, 5471.945280547144, 5473.72631368422, 5475.342958844964, 5476.315460567429, 5481.177823326533, 5347.0, 5393.379761327497, 5393.460653934575, 5394.622376433612, 5442.891142177055, 5283.313169287968, 5319.414864364994, 5514.641548299348, 5559.1661250811085, 5596.552275817843, 5316.521513063843, 5408.729563521887, 5413.079776438094, 5414.783408663609, 5467.261919640798], "value": 5150.690148562976, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.9114178444125, 646.1051443750406, 653.0335104046014, 657.3623585122435, 657.9702765172505, 36920.63079369206, 37934.96520278378, 37959.240815183715, 37984.0, 38007.23985520288, 40375.0, 41214.58785412145, 41245.0, 41270.38094428578, 41299.0, 8548.273396761282, 8781.736547903542, 8803.163699448542, 8810.38327317088, 8815.426997245191, 8670.13298670131, 8800.603972821238, 8802.691905783298, 8804.64781408743, 8805.95597022014, 8761.211490965812, 8810.691625793099, 8851.690190843321, 8857.689980850671, 8865.157810008071, 8730.476171429702, 8790.033096359395, 8801.075887031897, 8801.47191168532, 8806.031336552975, 8710.782230444227, 8825.69110081144, 8826.86759698605, 8828.249598784047, 8830.293576513928, 41069.58930410688, 41596.79201604003, 41620.583794161976, 41693.79153104215, 41745.373819392735, 3614.34941710492, 3712.628737126293, 3758.49260349852, 3780.7731536107644, 4038.858639947617, 31948.0, 32477.35045299081, 32488.512672309935, 32506.187345316557, 32636.347273054405, 4104.548499665035, 4153.185540243653, 4195.97728746603, 4225.830966761326, 4228.640565551932, 278756.0, 278958.0, 280613.0, 280694.0, 281395.5930220356, 54646.453535464934, 55449.336519904464, 55512.72243638756, 55582.0, 55593.722031390396, 53054.7347263269, 61765.0, 62981.27640426986, 63062.693730627485, 63146.0, 16370.754438683429, 16489.0, 16667.49997500077, 16670.666586668198, 16707.582310442336, 10620.362778233331, 12888.871111288767, 12958.611241662766, 12964.935175324063, 12975.870241297655, 15596.376144954302, 15816.367345306058, 15910.204489775697, 15952.601184970237, 16152.676946461004, 6443.970149402923, 6529.412352888168, 6613.338666133444, 6641.767538136239, 6747.291534388916, 989.7871957529165, 1017.8574999500075, 1036.3937096798456, 1090.8309212072143, 1207.486818102303, 30282.394352113275, 30484.237894052825, 30638.54042189369, 32719.836400817843, 33099.17252068718, 1119.4906317625462, 1213.6419756171952, 1214.3745970825032, 1232.89520390766, 1244.4897591987242, 15365.25061811652, 15594.454194103151, 15617.765733514007, 15761.369545218293, 15836.683266334609, 338342.0, 342304.0, 343403.0, 344516.0, 344607.0, 50305.49694503081, 50889.23666145011, 50899.49100509022, 54131.18803217955, 54183.729081354344, 1392.5683038258173, 1431.7923901034292, 1435.411481292665, 1454.316471258507, 1472.6686495538418, 27949.580756288266, 30061.0, 30136.84931575328, 31858.362832743678, 31899.0, 45529.54470455385, 45555.77222113868, 46099.3085103717, 46173.53826461827, 46185.30722039105, 280602.0, 281713.0, 282899.0, 283573.0, 284038.0, 24448.266552003468, 26739.197824065308, 27886.860565697043, 27888.58167127454, 27889.0, 39741.60258397379, 41156.38265426081, 41592.792036039624, 48078.759606203144, 49637.25544116769, 36383.0, 37314.25371492609, 39110.217795644494, 39488.21023579455, 39566.61516846897, 38426.23147537089, 40467.595324046386, 40487.392689110275, 40502.392464113655, 40829.79585102056, 38696.41955370615, 39595.80202098971, 40840.795796022016, 40895.795521022206, 40919.181616366914, 33792.0, 34172.0, 35082.82458587691, 35095.47356789599, 35183.12042198965, 27499.587506187025, 27514.862425687745, 27624.30939226455, 27928.30179245535, 28022.507476695977], "value": 18976.51665989503, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.6132928400882, 393.3843534867933, 399.5185801109663, 400.7755656832174, 406.54670042902166, 14204.786928196085, 16501.0, 18042.819571804277, 18613.62772744546, 18752.343667971625, 15050.548483545484, 16817.495475135736, 17528.123593820288, 17747.55631109223, 18528.166232519536, 5560.77756889725, 5700.914486282709, 5708.687001989545, 5712.457316554921, 5724.370319264877, 4057.168280502505, 5692.8861422771515, 5725.4847063764255, 6113.449789518942, 6432.5497215194955, 6314.968425157867, 6347.904781428282, 6404.039394090877, 6407.0, 6408.487321014308, 6132.233470816126, 6216.7824126155365, 6320.71556779946, 6342.238931328224, 6349.2698339690605, 5592.049351610195, 5592.720363981784, 5593.412691667357, 5598.216249725042, 6549.738010479623, 25144.87427562869, 25813.35466613331, 25912.22263332103, 25913.22260332193, 26491.0, 2602.285804992264, 2669.9332516687045, 3007.7293043626178, 3220.1949512621845, 3378.864845406181, 15400.922995385063, 15414.537563873102, 15658.138802365866, 15852.76220856688, 16304.0, 2063.1850419084376, 2163.5023944492855, 2206.051397898919, 2225.121077174523, 2227.7772222777753, 88961.55519222427, 91108.81119160863, 91116.0, 91277.54361228218, 93418.53290733503, 17133.65732685364, 17393.217305221297, 17410.47768566945, 17549.21028553718, 17571.209295581728, 30886.845565772026, 31009.534856977163, 31059.0, 31154.22114447157, 31202.68797312043, 9651.517424128766, 9946.916455898694, 9969.202463802878, 9981.550830212658, 10095.545700443497, 7305.159906610807, 7970.4819186752675, 8042.557659328793, 8100.3519718422385, 8461.873071903927, 7717.459777815611, 8470.322374210049, 8545.487270763773, 8702.912970870337, 8713.782155446037, 3063.5864158338786, 3334.3164651246434, 3334.699877011081, 3400.285939952638, 3538.3454060998797, 950.9857352139724, 1036.5180191211123, 1041.4063983529315, 1073.3077165228383, 1161.8431511745976, 18128.274869005356, 18442.0, 18955.336563220222, 19000.61998760017, 19381.51546211356, 1010.9039641234218, 1013.1185868294483, 1030.160419258302, 1048.2662136504478, 1065.6536625596711, 10424.426656533964, 10631.202659800545, 10971.177161712903, 11050.33697978124, 11088.889111108947, 471005.0, 476270.0, 478649.0, 509225.0, 509486.0, 85892.57053714691, 88819.55590222008, 88991.0, 89775.55112224528, 90121.54939225262, 1022.6778564752079, 1051.2693677893944, 1053.2995557954027, 1069.235496619905, 1076.1229597877705, 16493.505194844172, 16660.58348541296, 17045.659086818443, 17130.914345428195, 17590.73613895767, 37354.0, 37762.43356349712, 38333.4249986256, 38587.421188681634, 38755.0, 48944.510554894, 53904.730476347366, 55525.44474555203, 55879.44120558905, 56103.0, 8550.57247137653, 8714.128587141235, 8831.735047948572, 8864.069272726529, 8908.242799361868, 9662.468564229035, 13039.152455090389, 13393.66515837112, 13475.797863032258, 13785.241811700453, 10717.9464102679, 11807.409629518317, 11935.283882967049, 12036.879631203576, 12121.69695757613, 11314.0, 11387.0321022714, 11499.885001150216, 11652.94173529127, 11678.299302041905, 11828.172027958304, 11948.641540753792, 11953.0, 12069.57756478519, 12218.505018225325, 8174.836503269782, 8189.201195984547, 8195.057568379712, 8253.628586713732, 9125.498097604695, 8219.0, 10372.377657340583, 11462.713432164262, 11724.941375293069, 11923.940380298043], "value": 10103.451372721347, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.6167842164984, 1469.9118052916822, 1536.0630015690429, 1650.3064427724355, 1800.972985405219, 7890.329322007629, 8167.714130005451, 8193.631286592103, 8212.712555060574, 8222.835543289131, 8011.5193088414735, 8161.510309381427, 8166.265036146746, 8175.0, 8176.918230817705, 1951.9121639526184, 2181.291080398869, 2200.559888022398, 2225.4658881868318, 2269.1377276634857, 2294.678744975696, 2314.907403703858, 2317.0152685108847, 2317.9536409271805, 2322.7677232276724, 2219.2454565447742, 2268.534950335177, 2269.693591365169, 2270.182734215681, 2270.9772902271015, 1944.1542928826007, 2171.250918433138, 2214.933551993443, 2238.742544607375, 2242.7869352411494, 2179.030331502466, 2191.824654027674, 2204.4048107011176, 2205.106931692651, 2218.2457964292216, 7907.44647874649, 7999.080105787851, 8008.199180082003, 8009.439339246255, 8024.919750802534, 4780.0, 4936.753162341868, 4943.480934501861, 4948.653594248404, 5007.974960125176, 3286.572745543088, 3312.9834350828332, 3352.2625022495013, 3400.489926511043, 3405.4721518164724, 1032.7418145463635, 1040.7398150462384, 1049.58541376156, 1054.0671505717419, 1088.6244245735209, 11173.329600224013, 11207.0, 11211.047060999825, 11223.494942727597, 11271.32372057671, 4167.74993500391, 4170.207660544487, 4323.459567554056, 4331.761753103609, 4345.782710864444, 9515.524223788783, 9519.286053545873, 9523.857142142873, 9559.761005974906, 9560.043995600385, 4284.700070995001, 4959.380077490314, 4960.404751429852, 4973.577245934064, 4986.501349864984, 4502.954970450253, 4624.722516649012, 4628.5371462853445, 4650.023495066006, 4669.929951050737, 3681.9263614728093, 3699.667029967316, 3765.2281282337053, 3797.4493698413576, 3800.1639639279315, 1151.1826603111883, 1195.8266051422495, 1209.8971587414992, 1217.7077501399601, 1219.951201951912, 900.4417261298037, 1042.95828166874, 1049.5539395756773, 1054.5307338234566, 1063.1335461598771, 5895.263092113486, 5932.911006334908, 5942.64344139353, 5984.401559843981, 6173.69131543421, 913.584319134792, 917.2157805076563, 953.413650604873, 1006.2050979726081, 1007.1892126837965, 4134.235166494148, 4199.160167966358, 4216.64158546527, 4225.260579398654, 4233.301505251599, 9944.204463642891, 10362.481875906175, 10369.0, 10385.2211084169, 10396.896031039743, 10195.898041019644, 10214.642487512901, 10239.539220734936, 10248.6412975547, 10253.282270241012, 7624.58064806441, 7812.414068944852, 7818.0, 7863.13505514393, 7870.488418252795, 5618.297712785902, 5731.5128214100605, 5830.708464576839, 5874.118882167709, 5955.493783028491, 9364.95317523408, 9394.577244023898, 9490.145886870216, 9516.143547080796, 9523.857142143012, 9176.908230917605, 9394.765130871783, 9690.660826871026, 9701.805963880543, 9784.608615655294, 6133.877322453437, 6687.899681504684, 6873.243943166148, 7033.859322813618, 7154.463415243774, 8070.071941726542, 8086.1913808618665, 8139.959300203461, 8176.936998190436, 8208.302294304813, 7052.153741551047, 7216.097987751531, 7243.732346839177, 7323.487355885037, 7372.262773722585, 6996.545224560387, 7078.787636370917, 7082.220955694766, 7175.246599107228, 7242.782716518513, 6439.420452159328, 6581.638009909501, 6611.570247933869, 6622.139121914121, 6639.834004149935, 4789.880252993703, 4814.711117332971, 4833.299171620025, 4835.709857408567, 4920.876978075434, 5013.548780609763, 5017.648764586444, 5052.545270925634, 5064.924026139537, 5081.18701007837], "value": 4419.899238595382, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.9499427588723, 328.81750628401244, 329.47119872604486, 338.0972802617013, 339.1944132684873, 5547.805826796058, 5640.323161220649, 5641.125625528045, 5642.746076426561, 5682.971585142069, 5529.3364796224405, 5533.205731105796, 5558.610897237195, 5565.972170139154, 5571.164325351199, 4122.505299364073, 4212.894677633069, 4398.3842262083335, 4403.845865394712, 4409.4488188976375, 4356.956430435703, 4372.366006928994, 4375.7593332366705, 4382.948092457819, 4395.252807022814, 4131.690123240754, 4336.891577710567, 4343.348497725351, 4344.956550434486, 4358.8474403395885, 4230.894227644319, 4353.867994321467, 4366.97816510917, 4369.880607905164, 4381.145676593065, 3850.7497012694084, 3864.246471937973, 3876.8061596920325, 3876.8836934891997, 3887.630675085862, 5300.204969254604, 5356.196570514415, 5415.0523658360025, 5415.296011518517, 5420.566354691615, 5001.3998320201445, 5022.84931452057, 5029.591714319996, 5044.192929131358, 5057.69653820772, 5786.421357864222, 5834.883302333972, 5836.0, 5838.9124163137585, 5883.499902508292, 3913.882583522499, 4260.7656578888145, 4272.487301523837, 4278.572142785728, 4287.099709061101, 6075.574709770318, 6092.5430592705725, 6123.663198524079, 6129.21120889994, 6154.199954005995, 4144.254034273799, 4151.965536886964, 4170.791460426966, 4177.373393990925, 4180.163967206571, 4084.754149984224, 4213.578642135762, 4222.353282219937, 4232.915341693149, 4236.745795252295, 4856.757162141878, 4867.951320486821, 4889.804407823718, 4910.386201724785, 4912.6561140719805, 5865.4134586541, 5900.468957793819, 5901.049475262369, 5953.880922381528, 5979.641221526722, 1461.4665647038967, 1482.9925850370894, 1484.3765618440282, 1500.4673340964018, 1505.9698806024037, 920.8342498350228, 969.99030009699, 985.4678473624163, 998.5406712911996, 1001.519270750044, 5587.608867379245, 5630.887382252414, 5633.521150702154, 5660.29246344207, 5668.093105103164, 6006.0, 6006.429389208019, 6023.457888789942, 6025.578209525291, 6026.337102918676, 5479.835604931858, 5687.630304030307, 5805.36141024487, 5807.767689292466, 5807.82576522705, 3257.446234140202, 3282.409166350032, 3283.835808209628, 3287.3096649703352, 3290.68738469845, 6205.410486003822, 6299.622022678563, 6321.683915804283, 6329.398707122816, 6347.55567110307, 6249.406306400884, 6278.591891527036, 6281.780137695158, 6287.1512345832725, 6293.402126797947, 6111.174991376108, 6321.589096708699, 6327.766085613353, 6328.905066424008, 6340.144080549159, 6097.5731698780655, 6121.265448146251, 6129.908051379322, 6144.631322120687, 6146.0, 6007.879842403215, 6011.969940150271, 6038.879222415615, 6040.335563088145, 6043.848903777441, 6033.456988871023, 6238.656873871981, 6283.0, 6289.308176100534, 6295.048534954179, 4193.056562273464, 4204.873853784391, 4205.600467955539, 4209.0, 4210.3369169177395, 5969.283685957713, 6104.4200800923845, 6126.6017708848785, 6128.387161283836, 6130.969345153246, 5887.911681324869, 5921.407859214217, 5922.851928701644, 5924.31870334917, 5936.910946335895, 5838.970805145947, 5883.0881213411985, 5896.705164741831, 5906.143609176731, 5906.320773111147, 5402.189671549142, 5644.463775941444, 5805.477507024388, 5808.796692115756, 5841.007028805028, 5962.25471816023, 5964.254468191476, 5977.6114552553945, 5988.101784732151, 5995.0, 5648.745806438638, 5830.300363956352, 5836.445537674084, 5845.269341332333, 5846.94153058464], "value": 4476.400532201597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.2229728057805, 757.3335464790987, 807.5154907055771, 814.4054839966824, 819.872919697447, 14688.339024743886, 14998.550043498684, 15085.472008479705, 15097.320620572073, 15098.245087745596, 14348.497802576912, 14429.5671129866, 14479.058861174017, 14481.0, 14509.782353264709, 697.5779653309751, 714.0895358418006, 716.6524235745659, 846.3229416466818, 860.1484530315006, 711.1466240511381, 819.9877001844977, 852.526847599582, 854.1714536899229, 855.1064137975799, 864.5331520978664, 868.1231955724701, 868.3487384461654, 868.9652413903468, 872.0494660819705, 734.3024127079296, 861.8060936289316, 868.7524055644175, 870.9869351959726, 871.9607617657189, 631.1763149090435, 636.805774238857, 649.302000349626, 649.506375154881, 649.5550547874674, 13951.093178943438, 13967.022308438414, 14025.64935876601, 14074.507392241223, 14145.929270353685, 14343.928280358636, 14774.965752397336, 14786.704265914621, 14858.777118343234, 15005.24973751319, 10765.246432749711, 10793.78412431747, 11982.101342399268, 12060.939695301555, 12105.273683578926, 3990.521137463523, 4008.3834174813, 4066.63400293972, 4073.429719839225, 4149.2738770715005, 55384.0, 57193.42806571923, 57207.427925721044, 57237.0, 58956.11565826516, 11676.0, 11816.113791465674, 11938.164328496921, 11941.16411851162, 12256.14207005501, 20932.686009709865, 21004.159833606485, 21147.154113835273, 21166.78833211648, 21175.894120529298, 8675.175858293453, 8809.0, 8886.777830554287, 8989.730308090768, 9104.453732776055, 4743.762811859393, 4765.523447655276, 4975.875603109951, 5019.949800502021, 5072.137736584789, 3260.8858689946214, 3328.21786880083, 3340.1148695595684, 3344.2809795894, 3345.6487068857905, 577.4831525784483, 579.9217105690763, 588.0708480600641, 588.2999230915283, 595.6098755315276, 602.421675191812, 606.7876243314893, 607.9756809727561, 716.5449939288485, 741.8405042915799, 8912.287017038621, 9140.451572905647, 9162.358634895627, 9238.260939124853, 9389.765255868659, 686.3445409633788, 756.6216891554222, 759.9848003039908, 773.5899973014311, 788.5268838696851, 3932.4297976793205, 3940.4483372327322, 4010.3583426651594, 4020.1254859847377, 4076.0, 52848.47151528512, 55034.72482637561, 63877.68061159757, 64248.67875660592, 64290.357096429376, 49691.50308496869, 51074.0, 51359.22961155586, 51418.228726569134, 51830.48169518256, 45355.319670204975, 45649.0, 46858.29712554315, 47240.76379618148, 47337.763311183226, 9167.854018247719, 9812.165965892978, 9854.655087071918, 9916.405015699082, 9982.550785214538, 33300.33399331952, 33366.332673346886, 33957.151071223416, 34004.319913602085, 34963.65036349706, 41898.79050604728, 44018.559814401444, 44494.55505444904, 44566.554334457534, 45153.774231128635, 12392.75214495723, 12477.251364918135, 12511.311877846805, 12790.0, 12852.164609300366, 14031.929840350733, 14124.43502259898, 14339.211343376215, 14733.852661473247, 14895.0, 13031.869681303446, 13071.934640326737, 13258.734825303247, 13343.132696375093, 13674.0, 12215.57245496439, 12284.447199876206, 12305.56930507428, 12537.937310313755, 12577.559785407468, 12691.93654031724, 12854.742905141658, 13537.187768733907, 13577.32113394346, 13689.452421903012, 10725.839112413476, 10984.670459886216, 11145.0, 11273.436328183721, 11332.830007549728, 10912.181586380895, 10918.23572349928, 11192.552297908318, 11245.269057511236, 11267.26762760418], "value": 6832.785554670914, "unit": "score"}], "name": "dromaeo_css", "value": 7149.174275074143, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 104.0, 110.0, 111.0, 111.0, 101.0, 109.0, 111.0, 110.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 127.0, 154.0, 178.0, 136.0, 155.0, 159.0, 129.0, 187.0, 137.0], "value": 151.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 102.0, 114.0, 72.0, 75.0, 71.0, 100.0, 103.0, 76.0], "value": 85.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 153.0, 92.0, 93.0, 93.0, 139.0, 94.0, 93.0, 151.0], "value": 109.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 127.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 57.0, 56.0, 59.0, 57.0, 56.0, 77.0, 56.0, 55.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 61.0, 61.0, 60.0, 61.0, 60.0, 61.0, 62.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 85.0, 76.0, 77.0, 77.0, 79.0, 87.0, 78.0, 78.0, 79.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 126.0, 124.0, 124.0, 126.0, 124.0, 125.0, 156.0, 125.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 164.0, 165.0, 167.0, 174.0, 172.0, 164.0, 164.0, 176.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 68.0, 67.0, 68.0, 67.0, 69.0, 68.0, 68.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1539.8999999999999, "alertThreshold": 2.0}]} 06:17:30 INFO - Return code: 0 06:17:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:17:30 ERROR - # TBPL SUCCESS # 06:17:30 INFO - Running post-action listener: _resource_record_post_action 06:17:30 INFO - Running post-run listener: _resource_record_post_run 06:17:31 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548376576; Read time: 8; Write time: 604008 06:17:31 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 254844928; Read time: 0; Write time: 367824 06:17:31 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293531648; Read time: 8; Write time: 236184 06:17:31 INFO - Running post-run listener: _upload_blobber_files 06:17:31 INFO - Blob upload gear active. 06:17:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:31 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 06:17:31 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'] 06:17:31 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 06:17:31 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:17:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:17:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:32 INFO - (blobuploader) - INFO - Done attempting. 06:17:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:17:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:17:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:33 INFO - (blobuploader) - INFO - Done attempting. 06:17:33 INFO - (blobuploader) - INFO - Iteration through files over. 06:17:33 INFO - Return code: 0 06:17:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:17:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:17:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe41780399a1f237cbde4e3e7fc9a1abf0ab405cce1d73103318872daabbc48e2240cca54fd3fd8622c8535d6ae4abfc1173eac8092817b5e4b545ec2dec119b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f1e45cddc2544ea2a39fdcc351bf2863a26932bc5fa5a230443a5a2c8292f0251679a7f6d5b827c1275e152ae3b1b761f39eafd787d9f4bdd63ca650f2ae568"} 06:17:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:17:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:17:33 INFO - Contents: 06:17:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe41780399a1f237cbde4e3e7fc9a1abf0ab405cce1d73103318872daabbc48e2240cca54fd3fd8622c8535d6ae4abfc1173eac8092817b5e4b545ec2dec119b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f1e45cddc2544ea2a39fdcc351bf2863a26932bc5fa5a230443a5a2c8292f0251679a7f6d5b827c1275e152ae3b1b761f39eafd787d9f4bdd63ca650f2ae568"} 06:17:33 INFO - Running post-run listener: copy_logs_to_upload_dir 06:17:33 INFO - Copying logs to upload dir... 06:17:33 INFO - mkdir: /builds/slave/test/build/upload/logs 06:17:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1227.794235 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-04-22 06:17:33.339828) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:33.343397) ========= 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=0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712 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/fe41780399a1f237cbde4e3e7fc9a1abf0ab405cce1d73103318872daabbc48e2240cca54fd3fd8622c8535d6ae4abfc1173eac8092817b5e4b545ec2dec119b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f1e45cddc2544ea2a39fdcc351bf2863a26932bc5fa5a230443a5a2c8292f0251679a7f6d5b827c1275e152ae3b1b761f39eafd787d9f4bdd63ca650f2ae568"} build_url:https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/c2U0fYwaS7qjPYdkg5HZnw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe41780399a1f237cbde4e3e7fc9a1abf0ab405cce1d73103318872daabbc48e2240cca54fd3fd8622c8535d6ae4abfc1173eac8092817b5e4b545ec2dec119b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f1e45cddc2544ea2a39fdcc351bf2863a26932bc5fa5a230443a5a2c8292f0251679a7f6d5b827c1275e152ae3b1b761f39eafd787d9f4bdd63ca650f2ae568"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:33.368276) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:33.368616) ========= 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=0274524eba3bdaad075c2247000001d4-1461329823.844031-1867427712 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 06:17:33.452946) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:17:33.453272) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:17:33.453590) ========= ========= Total master_lag: 0.16 =========