builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-064 starttime: 1466018545.55 results: success (0) buildid: 20160615093425 builduid: 6d6cb93510eb4f8682bd2f73fc4118a1 revision: 3dda03d4037e84f6a646ceb934e4e5e3afd7ec29 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.547014) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.547387) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.547684) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.575432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.575940) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-15 12:22:25-- 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% 20.6M=0.001s 2016-06-15 12:22:25 (20.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088432 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.680567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.680874) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014436 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.732259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:25.732583) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3dda03d4037e84f6a646ceb934e4e5e3afd7ec29 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3dda03d4037e84f6a646ceb934e4e5e3afd7ec29 --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=a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-15 12:22:25,907 truncating revision to first 12 chars 2016-06-15 12:22:25,907 Setting DEBUG logging. 2016-06-15 12:22:25,907 attempt 1/10 2016-06-15 12:22:25,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3dda03d4037e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-15 12:22:26,233 unpacking tar archive at: mozilla-beta-3dda03d4037e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.692047 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:26.438932) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:26.439206) ========= script_repo_revision: 3dda03d4037e84f6a646ceb934e4e5e3afd7ec29 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:26.439568) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:26.439828) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-15 12:22:26.447236) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-15 12:22:26.447514) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:22:26 INFO - MultiFileLogger online at 20160615 12:22:26 in /builds/slave/test 12:22:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:22:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:26 INFO - {'append_to_log': False, 12:22:26 INFO - 'base_work_dir': '/builds/slave/test', 12:22:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:22:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:26 INFO - 'branch': 'Mozilla-Beta', 12:22:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:26 INFO - 'config_files': ('talos/linux_config.py',), 12:22:26 INFO - 'default_actions': ('clobber', 12:22:26 INFO - 'read-buildbot-config', 12:22:26 INFO - 'download-and-extract', 12:22:26 INFO - 'populate-webroot', 12:22:26 INFO - 'create-virtualenv', 12:22:26 INFO - 'install', 12:22:26 INFO - 'run-tests'), 12:22:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:26 INFO - 'download_minidump_stackwalk': True, 12:22:26 INFO - 'download_symbols': 'ondemand', 12:22:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:26 INFO - 'installer_path': 'installer.exe', 12:22:26 INFO - 'log_level': 'info', 12:22:26 INFO - 'log_name': 'talos', 12:22:26 INFO - 'log_to_console': True, 12:22:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:22:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:22:26 INFO - 'opt_config_files': (), 12:22:26 INFO - 'pip_index': False, 12:22:26 INFO - 'sps_profile': False, 12:22:26 INFO - 'sps_profile_interval': 0, 12:22:26 INFO - 'suite': 'dromaeojs-e10s', 12:22:26 INFO - 'system_bits': '32', 12:22:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:26 INFO - 'title': 'talos-linux64-ix-064', 12:22:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:26 INFO - 'use_talos_json': True, 12:22:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:26 INFO - 'work_dir': 'build'} 12:22:26 INFO - ##### 12:22:26 INFO - ##### Running clobber step. 12:22:26 INFO - ##### 12:22:26 INFO - Running pre-action listener: _resource_record_pre_action 12:22:26 INFO - Running main action method: clobber 12:22:26 INFO - rmtree: /builds/slave/test/build 12:22:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:27 INFO - Running post-action listener: _resource_record_post_action 12:22:27 INFO - ##### 12:22:27 INFO - ##### Running read-buildbot-config step. 12:22:27 INFO - ##### 12:22:27 INFO - Running pre-action listener: _resource_record_pre_action 12:22:27 INFO - Running main action method: read_buildbot_config 12:22:27 INFO - Using buildbot properties: 12:22:27 INFO - { 12:22:27 INFO - "project": "", 12:22:27 INFO - "product": "firefox", 12:22:27 INFO - "script_repo_revision": "production", 12:22:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 12:22:27 INFO - "repository": "", 12:22:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 12:22:27 INFO - "buildid": "20160615093425", 12:22:27 INFO - "pgo_build": "True", 12:22:27 INFO - "basedir": "/builds/slave/test", 12:22:27 INFO - "buildnumber": 37, 12:22:27 INFO - "slavename": "talos-linux64-ix-064", 12:22:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:22:27 INFO - "platform": "ubuntu64_hw", 12:22:27 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:22:27 INFO - "branch": "mozilla-beta", 12:22:27 INFO - "repo_path": "releases/mozilla-beta", 12:22:27 INFO - "revision": "3dda03d4037e84f6a646ceb934e4e5e3afd7ec29", 12:22:27 INFO - "stage_platform": "linux64-pgo", 12:22:27 INFO - "builduid": "6d6cb93510eb4f8682bd2f73fc4118a1", 12:22:27 INFO - "slavebuilddir": "test" 12:22:27 INFO - } 12:22:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 12:22:27 INFO - Running post-action listener: _resource_record_post_action 12:22:27 INFO - ##### 12:22:27 INFO - ##### Running download-and-extract step. 12:22:27 INFO - ##### 12:22:27 INFO - Running pre-action listener: _resource_record_pre_action 12:22:27 INFO - Running main action method: download_and_extract 12:22:27 INFO - mkdir: /builds/slave/test/build/tests 12:22: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')]} 12:22:27 INFO - https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:22:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 12:22:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 12:22:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 12:22:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:22:34 INFO - Downloaded 1327 bytes. 12:22:34 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 12:22:34 INFO - Using the following test package requirements: 12:22:34 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 12:22:34 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:22:34 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 12:22:34 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'jsshell-linux-x86_64.zip'], 12:22:34 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:22:34 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 12:22:34 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 12:22:34 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 12:22:34 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:22:34 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 12:22:34 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:22:34 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:22: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')]} 12:22:34 INFO - https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:22:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 12:22:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 12:22:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 12:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:22:46 INFO - Downloaded 24185717 bytes. 12:22:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:22:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:22:48 INFO - Return code: 0 12:22:48 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:48 INFO - https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:22:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 12:22:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 12:22:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 12:22:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:22:49 INFO - Downloaded 24185717 bytes. 12:22:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:22:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:22:51 INFO - Return code: 0 12:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:51 INFO - https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:22:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 12:22:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 12:22:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 12:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:22:53 INFO - Downloaded 10840145 bytes. 12:22:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:22:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:22:53 INFO - Return code: 0 12:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:53 INFO - https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:22:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 12:22:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 12:22:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 12:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:22:56 INFO - Downloaded 56005149 bytes. 12:22:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 12:22:56 INFO - mkdir: /builds/slave/test/properties 12:22:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:22:56 INFO - Writing to file /builds/slave/test/properties/build_url 12:22:56 INFO - Contents: 12:22:56 INFO - build_url:https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 12:22:58 INFO - Running post-action listener: _resource_record_post_action 12:22:58 INFO - Running post-action listener: set_extra_try_arguments 12:22:58 INFO - ##### 12:22:58 INFO - ##### Running populate-webroot step. 12:22:58 INFO - ##### 12:22:58 INFO - Running pre-action listener: _resource_record_pre_action 12:22:58 INFO - Running main action method: populate_webroot 12:22:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:22:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:22:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:22:58 INFO - '--tppagecycles', 12:22:58 INFO - '7'], 12:22:58 INFO - 'tests': ['tsvgm']}}, 12:22:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:22:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:22:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:22:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:22:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['damp', 'tps']}, 12:22:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'tests': ['damp', 'tps']}, 12:22:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['dromaeo_dom']}, 12:22:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:22:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['a11yr', 12:22:58 INFO - 'ts_paint', 12:22:58 INFO - 'tpaint', 12:22:58 INFO - 'sessionrestore', 12:22:58 INFO - 'sessionrestore_no_auto_restore', 12:22:58 INFO - 'tabpaint']}, 12:22:58 INFO - 'other-e10s': {'tests': ['a11yr', 12:22:58 INFO - 'ts_paint', 12:22:58 INFO - 'tpaint', 12:22:58 INFO - 'sessionrestore', 12:22:58 INFO - 'sessionrestore_no_auto_restore', 12:22:58 INFO - 'tabpaint']}, 12:22:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:22:58 INFO - 'ts_paint', 12:22:58 INFO - 'tpaint', 12:22:58 INFO - 'sessionrestore', 12:22:58 INFO - 'sessionrestore_no_auto_restore', 12:22:58 INFO - 'tabpaint']}, 12:22:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:22:58 INFO - 'ts_paint', 12:22:58 INFO - 'tpaint', 12:22:58 INFO - 'sessionrestore', 12:22:58 INFO - 'sessionrestore_no_auto_restore', 12:22:58 INFO - 'tabpaint']}, 12:22:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['a11yr', 12:22:58 INFO - 'ts_paint', 12:22:58 INFO - 'tpaint', 12:22:58 INFO - 'sessionrestore', 12:22:58 INFO - 'sessionrestore_no_auto_restore', 12:22:58 INFO - 'tabpaint']}, 12:22:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['a11yr', 12:22:58 INFO - 'ts_paint', 12:22:58 INFO - 'tpaint', 12:22:58 INFO - 'sessionrestore', 12:22:58 INFO - 'sessionrestore_no_auto_restore', 12:22:58 INFO - 'tabpaint']}, 12:22:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['tsvgx', 12:22:58 INFO - 'tsvgr_opacity', 12:22:58 INFO - 'tart', 12:22:58 INFO - 'tscrollx', 12:22:58 INFO - 'cart']}, 12:22:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:22:58 INFO - 'tsvgr_opacity', 12:22:58 INFO - 'tart', 12:22:58 INFO - 'tscrollx', 12:22:58 INFO - 'cart']}, 12:22:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'talos_options': ['--disable-e10s'], 12:22:58 INFO - 'tests': ['tp5o']}, 12:22:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'tests': ['tp5o']}, 12:22:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'talos_options': ['--disable-e10s', 12:22:58 INFO - '--xperf_path', 12:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:58 INFO - 'tests': ['tp5n']}, 12:22:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:58 INFO - 'talos_options': ['--xperf_path', 12:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:58 INFO - 'tests': ['tp5n']}}, 12:22:58 INFO - 'talos.zip': {'path': '', 12:22:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:22:58 INFO - Running post-action listener: _resource_record_post_action 12:22:58 INFO - ##### 12:22:58 INFO - ##### Running create-virtualenv step. 12:22:58 INFO - ##### 12:22:58 INFO - Running pre-action listener: _resource_record_pre_action 12:22:58 INFO - Running main action method: create_virtualenv 12:22:58 INFO - Creating virtualenv /builds/slave/test/build/venv 12:22:58 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 12:22:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:22:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:22:58 INFO - Using real prefix '/usr' 12:22:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:22:59 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:03 INFO - Installing pip.................done. 12:23:03 INFO - Return code: 0 12:23:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:23:03 INFO - Using env: {'DISPLAY': ':0', 12:23:03 INFO - 'HOME': '/home/cltbld', 12:23:03 INFO - 'LANG': 'en_US.UTF-8', 12:23:03 INFO - 'LANGUAGE': 'en_US:en', 12:23:03 INFO - 'LOGNAME': 'cltbld', 12:23:03 INFO - 'MAIL': '/var/mail/cltbld', 12:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:03 INFO - 'MOZ_NO_REMOTE': '1', 12:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:03 INFO - 'NO_EM_RESTART': '1', 12:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:03 INFO - 'PWD': '/builds/slave/test', 12:23:03 INFO - 'SHELL': '/bin/bash', 12:23:03 INFO - 'SHLVL': '1', 12:23:03 INFO - 'TERM': 'linux', 12:23:03 INFO - 'TMOUT': '86400', 12:23:03 INFO - 'USER': 'cltbld', 12:23:03 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:03 INFO - '_': '/tools/buildbot/bin/python'} 12:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:03 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 12:23:03 INFO - Cleaning up... 12:23:03 INFO - Return code: 0 12:23:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 12:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:23:03 INFO - Using env: {'DISPLAY': ':0', 12:23:03 INFO - 'HOME': '/home/cltbld', 12:23:03 INFO - 'LANG': 'en_US.UTF-8', 12:23:03 INFO - 'LANGUAGE': 'en_US:en', 12:23:03 INFO - 'LOGNAME': 'cltbld', 12:23:03 INFO - 'MAIL': '/var/mail/cltbld', 12:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:03 INFO - 'MOZ_NO_REMOTE': '1', 12:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:03 INFO - 'NO_EM_RESTART': '1', 12:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:03 INFO - 'PWD': '/builds/slave/test', 12:23:03 INFO - 'SHELL': '/bin/bash', 12:23:03 INFO - 'SHLVL': '1', 12:23:03 INFO - 'TERM': 'linux', 12:23:03 INFO - 'TMOUT': '86400', 12:23:03 INFO - 'USER': 'cltbld', 12:23:03 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:03 INFO - '_': '/tools/buildbot/bin/python'} 12:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:03 INFO - Downloading/unpacking psutil>=0.7.1 12:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:05 INFO - Installing collected packages: psutil 12:23:05 INFO - Running setup.py install for psutil 12:23:05 INFO - building 'psutil._psutil_linux' extension 12:23:05 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 12:23:05 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 12:23:06 INFO - building 'psutil._psutil_posix' extension 12:23:06 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 12:23:06 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 12:23:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:06 INFO - Successfully installed psutil 12:23:06 INFO - Cleaning up... 12:23:06 INFO - Return code: 0 12:23:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:06 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 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:06 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 12:23:06 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 12:23:06 INFO - Using env: {'DISPLAY': ':0', 12:23:06 INFO - 'HOME': '/home/cltbld', 12:23:06 INFO - 'LANG': 'en_US.UTF-8', 12:23:06 INFO - 'LANGUAGE': 'en_US:en', 12:23:06 INFO - 'LOGNAME': 'cltbld', 12:23:06 INFO - 'MAIL': '/var/mail/cltbld', 12:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:06 INFO - 'MOZ_NO_REMOTE': '1', 12:23:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:06 INFO - 'NO_EM_RESTART': '1', 12:23:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:06 INFO - 'PWD': '/builds/slave/test', 12:23:06 INFO - 'SHELL': '/bin/bash', 12:23:06 INFO - 'SHLVL': '1', 12:23:06 INFO - 'TERM': 'linux', 12:23:06 INFO - 'TMOUT': '86400', 12:23:06 INFO - 'USER': 'cltbld', 12:23:06 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:06 INFO - '_': '/tools/buildbot/bin/python'} 12:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:23:06 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 12:23:06 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 12:23:06 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 12:23:06 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 12:23:06 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 12:23:06 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 12:23:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:23:08 INFO - Installing collected packages: mozsystemmonitor 12:23:08 INFO - Running setup.py install for mozsystemmonitor 12:23:08 INFO - Successfully installed mozsystemmonitor 12:23:08 INFO - Cleaning up... 12:23:08 INFO - Return code: 0 12:23:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:23:08 INFO - Using env: {'DISPLAY': ':0', 12:23:08 INFO - 'HOME': '/home/cltbld', 12:23:08 INFO - 'LANG': 'en_US.UTF-8', 12:23:08 INFO - 'LANGUAGE': 'en_US:en', 12:23:08 INFO - 'LOGNAME': 'cltbld', 12:23:08 INFO - 'MAIL': '/var/mail/cltbld', 12:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:08 INFO - 'MOZ_NO_REMOTE': '1', 12:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:08 INFO - 'NO_EM_RESTART': '1', 12:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:08 INFO - 'PWD': '/builds/slave/test', 12:23:08 INFO - 'SHELL': '/bin/bash', 12:23:08 INFO - 'SHLVL': '1', 12:23:08 INFO - 'TERM': 'linux', 12:23:08 INFO - 'TMOUT': '86400', 12:23:08 INFO - 'USER': 'cltbld', 12:23:08 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:08 INFO - '_': '/tools/buildbot/bin/python'} 12:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:08 INFO - Downloading/unpacking blobuploader==1.2.4 12:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:10 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:11 INFO - Downloading docopt-0.6.1.tar.gz 12:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:11 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:11 INFO - Running setup.py install for blobuploader 12:23:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:11 INFO - Running setup.py install for requests 12:23:11 INFO - Running setup.py install for docopt 12:23:11 INFO - Successfully installed blobuploader requests docopt 12:23:11 INFO - Cleaning up... 12:23:12 INFO - Return code: 0 12:23:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:12 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 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:12 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 12:23:12 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 12:23:12 INFO - Using env: {'DISPLAY': ':0', 12:23:12 INFO - 'HOME': '/home/cltbld', 12:23:12 INFO - 'LANG': 'en_US.UTF-8', 12:23:12 INFO - 'LANGUAGE': 'en_US:en', 12:23:12 INFO - 'LOGNAME': 'cltbld', 12:23:12 INFO - 'MAIL': '/var/mail/cltbld', 12:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:12 INFO - 'MOZ_NO_REMOTE': '1', 12:23:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:12 INFO - 'NO_EM_RESTART': '1', 12:23:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:12 INFO - 'PWD': '/builds/slave/test', 12:23:12 INFO - 'SHELL': '/bin/bash', 12:23:12 INFO - 'SHLVL': '1', 12:23:12 INFO - 'TERM': 'linux', 12:23:12 INFO - 'TMOUT': '86400', 12:23:12 INFO - 'USER': 'cltbld', 12:23:12 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:12 INFO - '_': '/tools/buildbot/bin/python'} 12:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:12 INFO - Running setup.py (path:/tmp/pip-2pWoiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:12 INFO - Running setup.py (path:/tmp/pip-sM6aF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:12 INFO - Running setup.py (path:/tmp/pip-DNgqFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:12 INFO - Running setup.py (path:/tmp/pip-9Q8tOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:12 INFO - Running setup.py (path:/tmp/pip-658qBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:12 INFO - Running setup.py (path:/tmp/pip-9Ybkin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:12 INFO - Running setup.py (path:/tmp/pip-V2uB5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:12 INFO - Running setup.py (path:/tmp/pip-Iv2OGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:12 INFO - Running setup.py (path:/tmp/pip-YOEHbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:12 INFO - Running setup.py (path:/tmp/pip-SPTJtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:12 INFO - Running setup.py (path:/tmp/pip-UBnUl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:13 INFO - Running setup.py (path:/tmp/pip-9pBcIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:13 INFO - Running setup.py (path:/tmp/pip-rmMBG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:13 INFO - Running setup.py (path:/tmp/pip-rVp9EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:13 INFO - Running setup.py (path:/tmp/pip-iNcXTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:13 INFO - Running setup.py (path:/tmp/pip-T5ADGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:13 INFO - Running setup.py (path:/tmp/pip-B57Znu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:13 INFO - Running setup.py install for manifestparser 12:23:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:13 INFO - Running setup.py install for mozcrash 12:23:13 INFO - Running setup.py install for mozdebug 12:23:13 INFO - Running setup.py install for mozdevice 12:23:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:23:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:13 INFO - Running setup.py install for mozfile 12:23:13 INFO - Running setup.py install for mozhttpd 12:23:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Running setup.py install for mozinfo 12:23:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Running setup.py install for mozInstall 12:23:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Running setup.py install for mozleak 12:23:14 INFO - Running setup.py install for mozlog 12:23:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Running setup.py install for moznetwork 12:23:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Running setup.py install for mozprocess 12:23:14 INFO - Running setup.py install for mozprofile 12:23:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Running setup.py install for mozrunner 12:23:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:23:14 INFO - Running setup.py install for mozscreenshot 12:23:15 INFO - Running setup.py install for moztest 12:23:15 INFO - Running setup.py install for mozversion 12:23:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:23:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:23:15 INFO - Cleaning up... 12:23:15 INFO - Return code: 0 12:23:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:15 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 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:15 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 12:23:15 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 12:23:15 INFO - Using env: {'DISPLAY': ':0', 12:23:15 INFO - 'HOME': '/home/cltbld', 12:23:15 INFO - 'LANG': 'en_US.UTF-8', 12:23:15 INFO - 'LANGUAGE': 'en_US:en', 12:23:15 INFO - 'LOGNAME': 'cltbld', 12:23:15 INFO - 'MAIL': '/var/mail/cltbld', 12:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:15 INFO - 'MOZ_NO_REMOTE': '1', 12:23:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:15 INFO - 'NO_EM_RESTART': '1', 12:23:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:15 INFO - 'PWD': '/builds/slave/test', 12:23:15 INFO - 'SHELL': '/bin/bash', 12:23:15 INFO - 'SHLVL': '1', 12:23:15 INFO - 'TERM': 'linux', 12:23:15 INFO - 'TMOUT': '86400', 12:23:15 INFO - 'USER': 'cltbld', 12:23:15 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:15 INFO - '_': '/tools/buildbot/bin/python'} 12:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:15 INFO - Running setup.py (path:/tmp/pip-cLFsCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:15 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)) 12:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:15 INFO - Running setup.py (path:/tmp/pip-ukcElM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:15 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)) 12:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:15 INFO - Running setup.py (path:/tmp/pip-xwsToK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:15 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)) 12:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:15 INFO - Running setup.py (path:/tmp/pip-ycLN6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:15 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)) 12:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:15 INFO - Running setup.py (path:/tmp/pip-4eJuIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:15 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)) 12:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:15 INFO - Running setup.py (path:/tmp/pip-nLX4JX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:15 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)) 12:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:15 INFO - Running setup.py (path:/tmp/pip-KSuYj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:16 INFO - Running setup.py (path:/tmp/pip-nGbaYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:16 INFO - Running setup.py (path:/tmp/pip-kXh11K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:16 INFO - Running setup.py (path:/tmp/pip-OZuohI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:16 INFO - Running setup.py (path:/tmp/pip-md4Y9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:16 INFO - Running setup.py (path:/tmp/pip-i8RQRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:16 INFO - Running setup.py (path:/tmp/pip-iBgnVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:16 INFO - Running setup.py (path:/tmp/pip-VPdbht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:16 INFO - Running setup.py (path:/tmp/pip-LifoHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:16 INFO - Running setup.py (path:/tmp/pip-XMsC5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:16 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)) 12:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:16 INFO - Running setup.py (path:/tmp/pip-UyFHDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:16 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)) 12:23:16 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)) 12:23:16 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)) 12:23:16 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)) 12:23:16 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)) 12:23:16 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)) 12:23:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:18 INFO - Downloading blessings-1.6.tar.gz 12:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:23:18 INFO - Installing collected packages: blessings 12:23:18 INFO - Running setup.py install for blessings 12:23:18 INFO - Successfully installed blessings 12:23:18 INFO - Cleaning up... 12:23:18 INFO - Return code: 0 12:23:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:19 INFO - Reading from file tmpfile_stdout 12:23:19 INFO - Current package versions: 12:23:19 INFO - argparse == 1.2.1 12:23:19 INFO - blessings == 1.6 12:23:19 INFO - blobuploader == 1.2.4 12:23:19 INFO - docopt == 0.6.1 12:23:19 INFO - manifestparser == 1.1 12:23:19 INFO - mozInstall == 1.12 12:23:19 INFO - mozcrash == 0.17 12:23:19 INFO - mozdebug == 0.1 12:23:19 INFO - mozdevice == 0.48 12:23:19 INFO - mozfile == 1.2 12:23:19 INFO - mozhttpd == 0.7 12:23:19 INFO - mozinfo == 0.9 12:23:19 INFO - mozleak == 0.1 12:23:19 INFO - mozlog == 3.1 12:23:19 INFO - moznetwork == 0.27 12:23:19 INFO - mozprocess == 0.22 12:23:19 INFO - mozprofile == 0.28 12:23:19 INFO - mozrunner == 6.11 12:23:19 INFO - mozscreenshot == 0.1 12:23:19 INFO - mozsystemmonitor == 0.0 12:23:19 INFO - moztest == 0.7 12:23:19 INFO - mozversion == 1.4 12:23:19 INFO - psutil == 3.1.1 12:23:19 INFO - requests == 1.2.3 12:23:19 INFO - wsgiref == 0.1.2 12:23:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 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 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:19 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 12:23:19 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 12:23:19 INFO - Using env: {'DISPLAY': ':0', 12:23:19 INFO - 'HOME': '/home/cltbld', 12:23:19 INFO - 'LANG': 'en_US.UTF-8', 12:23:19 INFO - 'LANGUAGE': 'en_US:en', 12:23:19 INFO - 'LOGNAME': 'cltbld', 12:23:19 INFO - 'MAIL': '/var/mail/cltbld', 12:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:19 INFO - 'MOZ_NO_REMOTE': '1', 12:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:19 INFO - 'NO_EM_RESTART': '1', 12:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:19 INFO - 'PWD': '/builds/slave/test', 12:23:19 INFO - 'SHELL': '/bin/bash', 12:23:19 INFO - 'SHLVL': '1', 12:23:19 INFO - 'TERM': 'linux', 12:23:19 INFO - 'TMOUT': '86400', 12:23:19 INFO - 'USER': 'cltbld', 12:23:19 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:19 INFO - '_': '/tools/buildbot/bin/python'} 12:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 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)) 12:23:19 INFO - Cleaning up... 12:23:19 INFO - Return code: 0 12:23:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:23:19 INFO - Using env: {'DISPLAY': ':0', 12:23:19 INFO - 'HOME': '/home/cltbld', 12:23:19 INFO - 'LANG': 'en_US.UTF-8', 12:23:19 INFO - 'LANGUAGE': 'en_US:en', 12:23:19 INFO - 'LOGNAME': 'cltbld', 12:23:19 INFO - 'MAIL': '/var/mail/cltbld', 12:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:19 INFO - 'MOZ_NO_REMOTE': '1', 12:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:19 INFO - 'NO_EM_RESTART': '1', 12:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:19 INFO - 'PWD': '/builds/slave/test', 12:23:19 INFO - 'SHELL': '/bin/bash', 12:23:19 INFO - 'SHLVL': '1', 12:23:19 INFO - 'TERM': 'linux', 12:23:19 INFO - 'TMOUT': '86400', 12:23:19 INFO - 'USER': 'cltbld', 12:23:19 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:19 INFO - '_': '/tools/buildbot/bin/python'} 12:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:19 INFO - Downloading/unpacking jsonschema 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:21 INFO - Downloading/unpacking functools32 (from jsonschema) 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:21 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:21 INFO - warning: no files found matching '*.txt' 12:23:21 INFO - no previously-included directories found matching 'build' 12:23:21 INFO - no previously-included directories found matching 'dist' 12:23:21 INFO - no previously-included directories found matching '.git*' 12:23:21 INFO - Installing collected packages: jsonschema, functools32 12:23:21 INFO - Running setup.py install for functools32 12:23:21 INFO - warning: no files found matching '*.txt' 12:23:21 INFO - no previously-included directories found matching 'build' 12:23:21 INFO - no previously-included directories found matching 'dist' 12:23:21 INFO - no previously-included directories found matching '.git*' 12:23:21 INFO - Successfully installed jsonschema functools32 12:23:21 INFO - Cleaning up... 12:23:21 INFO - Return code: 0 12:23:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda3e633f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x201f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', '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 12:23: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', 'flake8'] in /builds/slave/test/build 12:23: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 flake8 12:23:21 INFO - Using env: {'DISPLAY': ':0', 12:23:21 INFO - 'HOME': '/home/cltbld', 12:23:21 INFO - 'LANG': 'en_US.UTF-8', 12:23:21 INFO - 'LANGUAGE': 'en_US:en', 12:23:21 INFO - 'LOGNAME': 'cltbld', 12:23:21 INFO - 'MAIL': '/var/mail/cltbld', 12:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:21 INFO - 'MOZ_NO_REMOTE': '1', 12:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:21 INFO - 'NO_EM_RESTART': '1', 12:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:21 INFO - 'PWD': '/builds/slave/test', 12:23:21 INFO - 'SHELL': '/bin/bash', 12:23:21 INFO - 'SHLVL': '1', 12:23:21 INFO - 'TERM': 'linux', 12:23:21 INFO - 'TMOUT': '86400', 12:23:21 INFO - 'USER': 'cltbld', 12:23:21 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:21 INFO - '_': '/tools/buildbot/bin/python'} 12:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:22 INFO - Downloading/unpacking flake8 12:23:22 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 12:23:22 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 12:23:22 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 12:23:22 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 12:23:22 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 12:23:22 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 12:23:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:23:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:23:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:23:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:23:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:23:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:23:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:23:24 INFO - no previously-included directories found matching 'docs/_build' 12:23:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:23:24 INFO - Running setup.py install for pep8 12:23:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:23:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:23:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:23:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:23:24 INFO - no previously-included directories found matching 'docs/_build' 12:23:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:23:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:23:24 INFO - Cleaning up... 12:23:24 INFO - Return code: 0 12:23:24 INFO - Running post-action listener: _resource_record_post_action 12:23:24 INFO - Running post-action listener: _start_resource_monitoring 12:23:24 INFO - Starting resource monitoring. 12:23:24 INFO - ##### 12:23:24 INFO - ##### Running install step. 12:23:24 INFO - ##### 12:23:24 INFO - Running pre-action listener: _resource_record_pre_action 12:23:24 INFO - Running main action method: install 12:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:25 INFO - Reading from file tmpfile_stdout 12:23:25 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:25 INFO - Reading from file tmpfile_stdout 12:23:25 INFO - Output received: 12:23:25 INFO - Usage: mozinstall [options] installer 12:23:25 INFO - Options: 12:23:25 INFO - -h, --help show this help message and exit 12:23:25 INFO - -d DEST, --destination=DEST 12:23:25 INFO - Directory to install application into. [default: 12:23:25 INFO - "/builds/slave/test"] 12:23:25 INFO - --app=APP Application being installed. [default: firefox] 12:23:25 INFO - mkdir: /builds/slave/test/build/application 12:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:23:38 INFO - Reading from file tmpfile_stdout 12:23:38 INFO - Output received: 12:23:38 INFO - /builds/slave/test/build/application/firefox/firefox 12:23:38 INFO - Running post-action listener: _resource_record_post_action 12:23:38 INFO - ##### 12:23:38 INFO - ##### Running run-tests step. 12:23:38 INFO - ##### 12:23:38 INFO - Running pre-action listener: _resource_record_pre_action 12:23:38 INFO - Running main action method: run_tests 12:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:38 INFO - Python 2.7.3 12:23:38 INFO - Return code: 0 12:23:38 INFO - grabbing minidump binary from tooltool 12:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae410>, '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 0x20af310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20af7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:23:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:23:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:38 INFO - Return code: 0 12:23:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:23:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:23:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:23:39 INFO - Return code: 0 12:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:23:39 INFO - Using env: {'DISPLAY': ':0', 12:23:39 INFO - 'HOME': '/home/cltbld', 12:23:39 INFO - 'LANG': 'en_US.UTF-8', 12:23:39 INFO - 'LANGUAGE': 'en_US:en', 12:23:39 INFO - 'LOGNAME': 'cltbld', 12:23:39 INFO - 'MAIL': '/var/mail/cltbld', 12:23:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:39 INFO - 'MOZ_NO_REMOTE': '1', 12:23:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:39 INFO - 'NO_EM_RESTART': '1', 12:23:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:39 INFO - 'PWD': '/builds/slave/test', 12:23:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:39 INFO - 'SHELL': '/bin/bash', 12:23:39 INFO - 'SHLVL': '1', 12:23:39 INFO - 'TERM': 'linux', 12:23:39 INFO - 'TMOUT': '86400', 12:23:39 INFO - 'USER': 'cltbld', 12:23:39 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258', 12:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:39 INFO - '_': '/tools/buildbot/bin/python'} 12:23:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:23:39 INFO - mozversion application_buildid: 20160615093425 12:23:39 INFO - mozversion application_changeset: 3dda03d4037e84f6a646ceb934e4e5e3afd7ec29 12:23:39 INFO - mozversion application_display_name: Firefox 12:23:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:39 INFO - mozversion application_name: Firefox 12:23:39 INFO - mozversion application_remotingname: firefox 12:23:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:23:39 INFO - mozversion application_vendor: Mozilla 12:23:39 INFO - mozversion application_version: 48.0 12:23:39 INFO - mozversion platform_buildid: 20160615093425 12:23:39 INFO - mozversion platform_changeset: 3dda03d4037e84f6a646ceb934e4e5e3afd7ec29 12:23:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:23:39 INFO - mozversion platform_version: 48.0 12:23:39 INFO - using testdate: 1466018619 12:23:39 INFO - actual date: 1466018619 12:23:39 INFO - starting webserver on 'localhost:32948' 12:23:39 INFO - SUITE-START | Running 2 tests 12:23:39 INFO - TEST-START | dromaeo_css 12:23:39 INFO - operating with platform_type : linux_ 12:23:39 INFO - Initialising browser for dromaeo_css test... 12:23:39 INFO - TEST-INFO | started process 6332 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIxqJ5q/profile http://localhost:32948/getInfo.html) 12:23:43 INFO - PROCESS | 6332 | 12:23:43 INFO - PROCESS | 6332 | (process:6407): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 12:23:44 INFO - PROCESS | 6332 | __metrics Screen width/height:1600/1200 12:23:44 INFO - PROCESS | 6332 | colorDepth:24 12:23:44 INFO - PROCESS | 6332 | Browser inner width/height: 1024/697 12:23:44 INFO - PROCESS | 6332 | __metrics 12:23:44 ERROR - PROCESS | 6332 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 12:23:45 INFO - TEST-INFO | 6332: exit 0 12:23:45 INFO - Browser initialized. 12:23:45 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:45 INFO - TEST-INFO | started process 6458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIxqJ5q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:47 INFO - PROCESS | 6458 | 12:23:47 INFO - PROCESS | 6458 | (process:6525): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 12:23:57 INFO - PROCESS | 6458 | RSS: Main: 151007232 12:23:57 INFO - PROCESS | 6458 | 12:24:47 INFO - PROCESS | 6458 | 1466018687189 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] 12:26:54 INFO - PROCESS | 6458 | Cycle 1(1): loaded http://localhost:32948/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32948/tests/dromaeo/cssquery-ext.html) 12:26:55 INFO - PROCESS | 6458 | RSS: Main: 155480064 12:26:55 INFO - PROCESS | 6458 | 12:29:57 INFO - PROCESS | 6458 | Cycle 1(1): loaded http://localhost:32948/tests/dromaeo/cssquery-ext.html (next: http://localhost:32948/tests/dromaeo/cssquery-jquery.html) 12:29:58 INFO - PROCESS | 6458 | RSS: Main: 153165824 12:29:58 INFO - PROCESS | 6458 | 12:32:59 INFO - PROCESS | 6458 | Cycle 1(1): loaded http://localhost:32948/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32948/tests/dromaeo/cssquery-mootools.html) 12:33:00 INFO - PROCESS | 6458 | RSS: Main: 152363008 12:33:00 INFO - PROCESS | 6458 | 12:36:02 INFO - PROCESS | 6458 | Cycle 1(1): loaded http://localhost:32948/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32948/tests/dromaeo/cssquery-prototype.html) 12:36:02 INFO - PROCESS | 6458 | RSS: Main: 153276416 12:36:02 INFO - PROCESS | 6458 | 12:39:05 INFO - PROCESS | 6458 | Cycle 1(1): loaded http://localhost:32948/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32948/tests/dromaeo/cssquery-yui.html) 12:39:05 INFO - PROCESS | 6458 | RSS: Main: 152076288 12:39:05 INFO - PROCESS | 6458 | 12:42:07 INFO - PROCESS | 6458 | Cycle 1(1): loaded http://localhost:32948/tests/dromaeo/cssquery-yui.html (next: http://localhost:32948/tests/dromaeo/cssquery-dojo.html) 12:42:08 INFO - PROCESS | 6458 | RSS: Main: 155705344 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | __start_tp_report 12:42:08 INFO - PROCESS | 6458 | _x_x_mozilla_page_load 12:42:08 INFO - PROCESS | 6458 | _x_x_mozilla_page_load_details 12:42:08 INFO - PROCESS | 6458 | |i|pagename|runs| 12:42:08 INFO - PROCESS | 6458 | |0;dojo.html;3337.082302366849;3389.1188291044327;3399.371116343475;3433.690967812895;3435.519027336172;5633.63381380211;5653.4911857932775;5658.264425624673;5659.4340565943385;5697.829065128048;5648.971755141219;5658.632188907718;5658.660480371181;5660.179274005267;5664.830055098343;5113.693178409298;5147.691138531682;5151.227315902607;5178.741062946857;5196.662216955905;5145.3568303962;5203.817866374678;5274.340707411574;5275.973620131894;5290.206469029637;5197.376314842224;5258.815941442051;5288.312519372476;5296.152615581508;5309.920351194735;5256.737163141827;5263.052650522885;5269.894602107936;5271.103912334874;5271.235670827708;5038.244263360489;5105.5149760772665;5177.922331165036;5226.451222621608;5237.266782650432;5543.113101903676;5566.081596536566;5629.971850140723;5642.548596112301;5686.289213848269;5994.220751302347;6031.969840150815;6034.24571928509;6036.849078773022;6039.8792024159275;6035.245594300713;6112.541559383019;6114.724837382329;6128.961487065919;6129.846753831145;5749.453801888814;5759.510441612426;5760.193572899798;5769.8557536061935;5808.477237048686;6256.499480041586;6290.24517057956;6299.401556852092;6302.779402720883;6314.179156709598;5921.881562368814;5987.550933679991;6044.032954727222;6060.818175454743;6071.241094863142;6129.695011270001;6134.159752074449;6141.188349436927;6145.262568491809;6152.753889844356;6049.1833602463985;6054.576179667469;6110.083487476914;6120.663363515049;6141.84645383869;5980.85047873798;6074.179985701874;6124.785632502841;6136.3965394158;6139.216166136355;4660.53394660538;4665.160271151158;4678.625709943196;4689.60138388234;4692.976535117371;4179.6029377209115;4180.393842892763;4185.832566697359;4188.183304255641;4203.810828512725;5748.741306641211;5854.531637468992;5964.552658550626;5982.46157845796;6017.819465416044;6130.703893784946;6242.937570624235;6270.404311590391;6270.529710271748;6273.37266273369;5836.503897168704;5847.210626565445;5869.735861886225;5870.471657550841;5901.468867801918;5081.28861959326;5092.592592592584;5099.260607211933;5105.2086926526445;5119.974400127976;6394;6431.839204019843;6435.549511534147;6443.130177425987;6447.355264473609;6088;6116.235470566179;6152.2002139722445;6165.72254248551;6179.258488981351;6327.810165695037;6329;6347.270063942521;6383.93616063852;6384.201974753156;5929.377415371324;5988.520918326436;5989;6005.489533389536;6009;5740.540756739534;6076.969615151896;6082.330943596289;6126.15126988545;6167.5682702210415;5975.760969561169;5992.460678538952;6027.065804800263;6035.6076855004285;6036.607620504653;5872.412758724265;6029.57792954489;6029.728662210299;6035.78874739382;6036;5768.423157684366;5870.765169393177;5882.441168089024;5940.46535811779;5984.162217289496;5936.050231962865;5947.197128387786;5949.762009519571;5956.344802071855;5959.553033522417;5785.450382213683;5809.796657116981;5810.883782324246;5819.854503637443;5820.883582328245 12:42:08 INFO - PROCESS | 6458 | |1;ext.html;666.350308449262;682.8668409660116;700.9964950175248;731.5939653492313;742.6138408027824;31539.369212615762;32195.51706724401;39074.60925390745;39615.80192099042;39790.602093979054;42634.78682606583;43073.7846310768;43282.78358608203;43398.56601433985;43420.3486947696;8821.69124080659;8990.865137022949;9006.144416280476;9006.279497640206;9012.729618111467;8900.243479304234;8929.241014513775;8980.41627294227;8991.685291014815;9013.36906416551;9305.255579553654;9325.860112098324;9327.067293270653;9359.20446762026;9413.576389062475;9277.396969197014;9322.580483878208;9339.673111441101;9349.39228950116;9363.25093992479;8783.692570760057;8845.115488451167;8850.690225842129;8873.467591944504;8880.467171969638;43471.56528434707;43711.12577748459;44051.559484405065;44219.77890110561;44227.557724422986;3819.0452386903276;3932.4494570760116;4463.553644635543;4551.294549344857;4579.885502862422;32740;33158.6684133158;33438;34055.65944340575;34588.30823383518;4454.9777251113865;4492.415985921842;4506.909861802746;4532.093581283723;4535.233507560385;297025;297428;297797;298437;298442;40153.79923100366;40791.18417631631;64578.354216457235;65048.34951650518;65102.34897651058;40276.194476110315;40311.3953290695;40475.59524404718;40568.594314057074;40646.59353406487;14985.475508357154;15053.774193387108;15059.774103388458;15201.467948621745;15288.847111528965;13255.403506842214;13315.267660278583;13321.467141314239;13339.533116340881;13344.733105338033;9625.278104142217;9663.082007209305;9682.9031709682;9714.077162669535;9727.610895564098;3638.890833275006;3680.5215322007966;3749.9625003750157;3760.4359346098304;3762.6425489578446;1152.590830255264;1169.0997931592626;1179.2193787381116;1182.9349385783864;1214.8056310990198;32633.836830815693;33507.49738753921;33773.83113084419;33999.32001359959;34608.48087278693;1182.9231099978474;1198.161287099034;1232.5254776910954;1260.3950103950156;1267.0876968582606;17468.301267949395;17634.647307054045;17686.09060067856;17746.5563360917;17778.5555361117;463180;526373;531011;535650.286994258;543331;58441.707791461624;59244.70377648171;59443.702781485816;59474.40525594775;59576.40423595709;1423.2101183843067;1545.412743157593;1561.3988614383545;1571.4892659885584;1582.8733701303868;33350.499742503394;33501.66498334985;34004;34049.31901362009;34233.144171395914;56446.717766410904;57302;57367.42632573621;57399.426005739406;57454;438095;439063;440469;440605;441830;31525.527117092803;47648.2852757202;48262.27606585974;48526.75736621294;48906.26640600465;45084;45413.772931135136;45964.54035459736;46023.079538409715;46636.53363466458;40784.79607601943;42588.36117458178;42684.78657606692;42947.35578966381;44337.77831110824;40879.795601023;41037.38443923403;41611.58388416077;42258.36612450877;42724.1455170901;43878.78060609676;44241.55758442375;44329.11341773082;44560;44689.32966005448;35372.29255414929;36238.8188059058;36365.27269454649;37014.25971480609;37645.435318470794;29141.271468213465;29443;29556.261093471974;29770.404591908475;30009 12:42:08 INFO - PROCESS | 6458 | |2;jquery.html;426.93169092945124;430.9138172365524;433.5750964055224;435.7929983257953;436.4827679200147;18743.90628046861;19120.23519059236;19318.22727090915;19517.512062198435;19604.411867643994;18946.147423365946;19064.42806715801;19250.903745481253;19383.127759250834;19422.22311107554;5482.122860342346;5660.9716951415285;5734.79928202513;5734.971325143369;5927.2590926134235;3978.6817054635703;4151.8962025949295;5686.232358631573;5728.398518155596;5780.653160810344;5848.619839710405;5899.439553242457;5900.291964964189;5904.1734157218025;5922.170896074553;5365.892682146336;5585.916211256835;5601.80393686219;5843;5844.211031510734;5472.179173124024;5491.121420572689;5516.972415137899;5558.638688485235;5588.4411558844195;21911;22651.20720774765;22802.315930522112;22934.54130917389;23021.884890575606;2256.142665787007;2381.5594115088766;2399.3281881073326;2399.6040653292184;2412.541617092747;10384.740381490448;10479.738006549896;10534.947325263402;10543.525541350658;10900.400477973708;2022.2113375783456;2056.1364227024687;2184.3337781976493;2231.2079211879714;2304.9423764405856;74901;79951.20048799495;82355.81427802407;82800;83141.16858831394;11106.888931110747;11414;11551.075913926865;11663.533458661559;11858.110641701907;16205.594860128354;16268.75596866048;16468.09425481586;16491.83508164903;16502.33990640361;8535.359848011423;8541.53021583819;8563.314934805201;8629.22336989674;8629.654813807378;8147.796305092302;8188.707161994651;8199.508029518249;8256.463329883538;8270.131636178236;5037.722925239073;5094.4141423736;5136.203888397305;5146.433892271847;5196.168613021974;1942.786293507713;2062.4946888012532;2125.5111324395475;2133.775953524889;2159.1147629472157;968.3076600230869;1088.50473034769;1090.7654854206385;1101.983470247947;1126.1441304608393;18187.45437636873;18355.174017169258;18364.265429382944;19365.51586210356;19903.502412439804;1018.5467466977124;1049.506731836036;1075.172117469544;1095.1239008793093;1099.8130317845985;10560.524776384927;10898.291611045257;10901.781964360669;10908;10929.289596176382;525596;527114;527376;535826;537694;90046.5403050974;94171.52914235384;95433.5228323854;95621.5218923901;95634.52182739042;1084.8698156221303;1092.6612750047511;1098.5880294889416;1105.3699391346854;1119.7424592343807;16738.916305418395;16957.06736129525;17152.828471715624;17152.970821750736;17214;25037.499250014534;38427.42358864675;38795.80602097084;40484.3927341096;40562.59437405588;56200.15699764425;56246;56764.71617641886;56826.14760778674;57440;8783.165599268059;8825.955870220609;8990.460572365679;9008.639654414012;9025.232855207381;9730;12759.808602870778;12883.549075782303;12905.548305809252;12948.028897832512;11399.14506412039;11421.200515964136;11446.255993360404;11573.942130289295;11879.643610691694;11060.944695276472;11173.832392514281;11210.88789112131;11291.491882865126;11589.594364197214;11802.350870702194;11825.645230643095;11850.940745296219;11922.701932451759;12104.083130683124;8548.914510854811;8721.781955450911;8740.082291359317;8824.161704638049;8864.778380540538;11827.82258266144;12320.137590368588;12364.505419783109;12479.56321528742;12545.623631291075 12:42:08 INFO - PROCESS | 6458 | |3;mootools.html;1561.6720488697374;1564.7339952208124;1767.3372485318005;1875.8968256745873;1876.8738756745945;8441.240288374045;8509.319254459644;8589.613467393967;8708.259797917177;8731.126887311266;8675.60959756811;8730.86903696445;8773.780655483617;8782.824343513126;8809.95595022024;2433.0997530913587;2462.4705688277018;2467.4078221226864;2467.7902378297867;2473.4311108445067;2408.494216214597;2488.054539275073;2490.8380955237944;2492.202495201527;2493.9002439902383;2442.4870777136734;2468.3582268610203;2470.3947532854477;2472.6043832986725;2473.7649923257263;2425.890834912433;2433.6836211292507;2439.85360878347;2441.584930561809;2458.397692565324;2348.4481146930457;2351.58847201739;2351.9886448826996;2355.422921384272;2373.8338316317863;8573.828523429496;8611.26804221642;8644.740657780276;8644.870326945102;8651.178138076873;5528.530074943635;5592.217089607459;5632.633878797906;5655.208270842086;5657.43425657431;3482.2687235680537;3488.9302213955834;3537.4340105582983;3610.6389361063684;3631.473436351714;1156.4044517073714;1174.7591743692608;1181.7754626621002;1209.3046498263534;1210.878912108791;11947.283163010246;12014.759704805854;12033.338166400845;12086.516539338416;12092.697682557919;4887.169181239198;4887.462379138292;5010.448850626428;5111.923321150186;5115.923261151086;10358.53386597605;10395.376277423378;10423.583056677648;10424.061834434937;10437.530311136017;5517.889642207214;5561.63849349791;5576.553875689935;5599.076152434803;5616.073347897633;4781.832635857728;4886.169351210303;4951.430585482643;4953.975230123826;4962.528559786814;3966.900827479336;4018.819153138116;4061.289274376972;4159.916801664011;4174.707770456039;1139.9886001140048;1231.2735486063086;1232.3222227774688;1234.0744441668749;1241.2800575666092;928.4104593583123;988.951711186145;999.2905037423511;1045.11165509318;1054.4411461925192;5902.468777810018;5918.260217472816;5950.958582248089;5982.790602328898;6137.447629713347;495.86611614864506;500.73961539999;506.3164727617687;507.11255303219366;508.0879820721813;3979;4010.979945100256;4018.477597912253;4050.5139383274195;4061;10507.789844203073;10760.623378181726;10849.566017359375;10883.510242039127;10917.617883374203;10710.892891071146;10720.624778132727;10721.517531711092;10735.677929662123;10768.3000604962;7783.182765809622;8026.117127116013;8048.235417635316;8074.434789564792;8094.959525202336;5950.702464876652;6008.969955150196;6075.27096748393;6141.232345956755;6170.413810687977;9381.906180938104;9519.333646644669;9530.475823829756;9681.128698417177;9705.9029409705;9071.818563628822;9229.307801915018;9649;9697.466639334903;9749.610015599297;7424.220456852169;7465.776026719207;7572.39420846342;7585.241475852371;7594.126675432396;8166;8173.141820108803;8195.95902020486;8231.218034286732;8275.710350137717;7356.926430735838;7364.631768411665;7464.888026679496;7589.696412143452;7641.312281894656;6981.511294209559;7000.61496617691;7055.047568578177;7089.149302083783;7210;6885.449164066963;6900.861982760216;6912.792616221522;6922.723091076301;6950.165980082423;4765.475797662185;5017.1721665924715;5063.797448102182;5094.872628184176;5102.872428189325;4930.408350997904;4992.425871024879;5015.3480047593575;5060.746962651926;5076.771545280545 12:42:08 INFO - PROCESS | 6458 | |4;prototype.html;355.2539666699929;362.46174430970007;363.4112737365468;368.26714837473435;368.70135190495716;6369.6496692681885;6575.7369705211795;6621.205455345354;6637.535372523926;6681.4654827613795;6421.784484352903;6547;6561.0814485972005;6563.409293163614;6564.770233041844;4780.665353425262;4886.584640305578;4954.331165292684;4956.925646115311;4965.180745177059;4850.369451971239;4883.12103821312;4885.218365061591;4898.24077268024;4905.730184839832;4811.78346974387;4812.157872372339;4814.796300924769;4821.4696383397795;4822.903541929177;4816.277558366256;4821.734804585764;4823.348847905523;4825.372701548794;4836.709797412166;4156.958430415687;4168.2080404723;4168.749875007509;4175;4175.812088456027;6283.3088360280335;6294.339094395114;6294.527910406692;6297.992321228628;6302.810915672491;5569.832905012856;5622.803201887955;5713.771449142029;5718.42815718429;5719.857003574903;6812.48906332027;6837.794866154023;6839.794806155824;6850;6855.177378714586;4710.74090924999;4712.387389639325;4716.339712440261;4716.551927566876;4719.858404247878;7134.072570565793;7191.568505889663;7224.024756657836;7234.095738032746;7244.203137654854;4796.880077998078;4820.084184005028;4820.783064762024;4826.0830442215865;4827.131116399082;4727.621790256771;4829.782659780248;4834.95165048345;4838.467768545457;4854.927176092361;5644.8024319148635;5663.178839068312;5701.4298570142655;5717.142428635655;5718.25662663851;6941.201761797458;6952.409045231212;6953;6960.164780226405;6966.407855332252;1707.1976171199517;1730.3857130718666;1732.523556022104;1736.0885535093923;1736.6613510365357;1095.6439157273758;1123.1071298317693;1137.0676045642495;1139.7549526851767;1151.7984352738238;6765.594064356154;6770.5599136056;6777.051212830208;6778.118844550177;6779;7159.069320988342;7164.355208031198;7168.426525877917;7170.820729481805;7175.96412017947;6675.866482670416;6770;6770.830729231809;6818.2159051708695;6859.485538584527;3873.6248631735893;3907.0623050468253;3910.7066969977363;3911.9217615647526;3922.666573341241;7606.04924384452;7609.239076092346;7614.847703046019;7623.847523049619;7635.503692259986;7328.963355183297;7335;7343.412526997828;7356.668949897267;7363.558186508827;7461.067366579177;7578.810529736801;7596.164421913585;7599.886001709979;7601.809954751176;7143.964280178566;7175.856482870209;7200.89198662031;7201.927980720126;7211.062561867134;7074;7077.433805295461;7083.787486375417;7088.397486213729;7111.964440177766;7533;7564.092308922965;7580.241975802375;7592.126905405949;7594.772156835304;4720.693154944917;4789.736564488987;4859.319695242598;4862.270659401118;4874.171390863633;7155.749548765768;7195.784126476214;7198.280171982759;7209.603471808891;7219.061522002106;6976.5814051157095;6994.685239164148;6997.26528714498;7002.824929376807;7010.4041156500225;6938.028675985467;6983.860322793617;6990;6992.042228176118;7003.6498175092065;6709.496787740842;6798.76204332846;6812.455003599601;6831.69257383409;6841.418479429304;6976.581405115507;6982.895256571054;6985.1617805863625;7035.683394247373;7041.155061392666;6838.965805170942;6840.9315906842285;6840.9315906842285;6847.452203823782;6854.485913556603 12:42:08 INFO - PROCESS | 6458 | |5;yui.html;922.6862866625346;929.3866048408051;938.4322484896637;944.0889541592362;946.7017889364845;14629.780553291695;14683.26583670815;14803.481878134267;14831.480898168566;15017.249137543135;14074.64813379666;14190.43238270468;14231.857681423182;14242.145471271706;14322.069065510734;917.8531434970406;927.7587827164951;929.7210836748982;953.142172045158;954.4654993203793;911.9042500537447;926.9026752191024;927.772695689557;928.7074571509986;930.6230976454547;953.9761505962372;964.720231132974;966.3284017607765;966.7873067925045;967.5065716484609;950.4629884115487;962.345604988604;962.3936919740519;962.711186644011;963.7879666473365;706.4136766483806;706.752636577199;710;710.9644517774091;711.0045935690024;15663.216839157996;15697.293621787047;15706.293216805272;15753;15763.92118039414;14711.485098021518;14766.483173088998;14927.179005154712;15060.698786024328;15128.697426051527;11691.123165762518;12086.576969806014;12213.938930305292;12343.814842777365;12423.62729118128;3979.900502487432;3999.7800120993334;4064.7561146331313;4086.264472394968;4111.568285330057;57399.13901291484;57950.7102464485;58084.70957645227;59508.40491595115;59800;11023.393713345682;11068.335899846035;11080.5567777288;11364.431778411046;11391;20847.270345537832;20915.68626470604;20957.89521052385;20964.685529717066;21028;8644.092370301154;8735.25750311218;8761;8775.254103401283;8834.160754728291;5129.8717532062;5166.6125040621355;5216.165413533817;5220.425753167148;5234.502722241381;3672.9632703672623;3749.343864823645;3772.000419888732;3789.1663833956486;3833.961660383416;684.1926526698544;684.9006894000343;690.8169335126196;691.8201267670341;699.4299645788768;745.1505283976271;770.1412924589043;771.7376092128703;778.7585848386905;785.0618510879558;8670.176333248332;8688.348373871984;8706.52114133716;8864.290856731446;9633.915211970076;736.4660621049696;741;748.7716246544857;755.2636179724834;755.6826133024033;4393.51671316155;4447.021655235843;4497.865064048084;4504.774761261924;4550.453945526558;64128;64178.6791066051;64259;64496;64732;58260.41739582635;59058;59136;59601.70199148977;59714.70142649259;50232;50255.24617130746;52296.738516307174;52612.210816837774;52708;8512;8612.138786121337;8616.655333786579;8885.422447541148;8981.506017168856;37873.62126378701;37942.2411551773;38657.22685546217;39428;39680.60319396885;45867.311990319504;48613.75693121512;48930.51069489402;49532.25701614551;49939;10957.780844382909;10993.780124397626;11077.501512431798;11357.886421135683;11379.829302560303;12397.256164630151;12438.564650237198;12526.156216605708;12544.811827822408;12724.300163491096;12008.63974080779;13444.798328024892;13475.73048539004;13519.864801351861;14041.297935103408;11854.348010859485;12718;13364.93317533445;13393.866061339262;13451.798223026466;12924;13788.379522921694;13906.304684765519;14126.364313605707;14247.786283205967;11214.383208923588;11595.652130436101;11676.532938682358;11701.473433695333;11705.648830535098;11681.591144309908;11693.532258709556;11793.233439826385;11898.76202475963;11917.642470725892 12:42:08 INFO - PROCESS | 6458 | __end_tp_report 12:42:08 INFO - PROCESS | 6458 | __start_cc_report 12:42:08 INFO - PROCESS | 6458 | _x_x_mozilla_cycle_collect,321 12:42:08 INFO - PROCESS | 6458 | __end_cc_report 12:42:08 INFO - PROCESS | 6458 | __startTimestamp1466019728036__endTimestamp 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | ------- Summary: start ------- 12:42:08 INFO - PROCESS | 6458 | Number of tests: 6 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | [#0] dojo.html Cycles:165 Average:5683.33 Median:5921.88 stddev:641.17 (10.8%) stddev-sans-first:616.15 12:42:08 INFO - PROCESS | 6458 | Values: 3337.1 3389.1 3399.4 3433.7 3435.5 5633.6 5653.5 5658.3 5659.4 5697.8 5649.0 5658.6 5658.7 5660.2 5664.8 5113.7 5147.7 5151.2 5178.7 5196.7 5145.4 5203.8 5274.3 5276.0 5290.2 5197.4 5258.8 5288.3 5296.2 5309.9 5256.7 5263.1 5269.9 5271.1 5271.2 5038.2 5105.5 5177.9 5226.5 5237.3 5543.1 5566.1 5630.0 5642.5 5686.3 5994.2 6032.0 6034.2 6036.8 6039.9 6035.2 6112.5 6114.7 6129.0 6129.8 5749.5 5759.5 5760.2 5769.9 5808.5 6256.5 6290.2 6299.4 6302.8 6314.2 5921.9 5987.6 6044.0 6060.8 6071.2 6129.7 6134.2 6141.2 6145.3 6152.8 6049.2 6054.6 6110.1 6120.7 6141.8 5980.9 6074.2 6124.8 6136.4 6139.2 4660.5 4665.2 4678.6 4689.6 4693.0 4179.6 4180.4 4185.8 4188.2 4203.8 5748.7 5854.5 5964.6 5982.5 6017.8 6130.7 6242.9 6270.4 6270.5 6273.4 5836.5 5847.2 5869.7 5870.5 5901.5 5081.3 5092.6 5099.3 5105.2 5120.0 6394.0 6431.8 6435.5 6443.1 6447.4 6088.0 6116.2 6152.2 6165.7 6179.3 6327.8 6329.0 6347.3 6383.9 6384.2 5929.4 5988.5 5989.0 6005.5 6009.0 5740.5 6077.0 6082.3 6126.2 6167.6 5975.8 5992.5 6027.1 6035.6 6036.6 5872.4 6029.6 6029.7 6035.8 6036.0 5768.4 5870.8 5882.4 5940.5 5984.2 5936.1 5947.2 5949.8 5956.3 5959.6 5785.5 5809.8 5810.9 5819.9 5820.9 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | [#1] ext.html Cycles:180 Average:58289.78 Median:43178.28 stddev:113903.86 (263.8%) stddev-sans-first:114141.23 12:42:08 INFO - PROCESS | 6458 | Values: 666.4 682.9 701.0 731.6 742.6 31539.4 32195.5 39074.6 39615.8 39790.6 42634.8 43073.8 43282.8 43398.6 43420.3 8821.7 8990.9 9006.1 9006.3 9012.7 8900.2 8929.2 8980.4 8991.7 9013.4 9305.3 9325.9 9327.1 9359.2 9413.6 9277.4 9322.6 9339.7 9349.4 9363.3 8783.7 8845.1 8850.7 8873.5 8880.5 43471.6 43711.1 44051.6 44219.8 44227.6 3819.0 3932.4 4463.6 4551.3 4579.9 32740.0 33158.7 33438.0 34055.7 34588.3 4455.0 4492.4 4506.9 4532.1 4535.2 297025.0 297428.0 297797.0 298437.0 298442.0 40153.8 40791.2 64578.4 65048.3 65102.3 40276.2 40311.4 40475.6 40568.6 40646.6 14985.5 15053.8 15059.8 15201.5 15288.8 13255.4 13315.3 13321.5 13339.5 13344.7 9625.3 9663.1 9682.9 9714.1 9727.6 3638.9 3680.5 3750.0 3760.4 3762.6 1152.6 1169.1 1179.2 1182.9 1214.8 32633.8 33507.5 33773.8 33999.3 34608.5 1182.9 1198.2 1232.5 1260.4 1267.1 17468.3 17634.6 17686.1 17746.6 17778.6 463180.0 526373.0 531011.0 535650.3 543331.0 58441.7 59244.7 59443.7 59474.4 59576.4 1423.2 1545.4 1561.4 1571.5 1582.9 33350.5 33501.7 34004.0 34049.3 34233.1 56446.7 57302.0 57367.4 57399.4 57454.0 438095.0 439063.0 440469.0 440605.0 441830.0 31525.5 47648.3 48262.3 48526.8 48906.3 45084.0 45413.8 45964.5 46023.1 46636.5 40784.8 42588.4 42684.8 42947.4 44337.8 40879.8 41037.4 41611.6 42258.4 42724.1 43878.8 44241.6 44329.1 44560.0 44689.3 35372.3 36238.8 36365.3 37014.3 37645.4 29141.3 29443.0 29556.3 29770.4 30009.0 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | [#2] jquery.html Cycles:180 Average:30061.03 Median:22868.43 stddev:87304.42 (381.8%) stddev-sans-first:87520.97 12:42:08 INFO - PROCESS | 6458 | Values: 426.9 430.9 433.6 435.8 436.5 18743.9 19120.2 19318.2 19517.5 19604.4 18946.1 19064.4 19250.9 19383.1 19422.2 5482.1 5661.0 5734.8 5735.0 5927.3 3978.7 4151.9 5686.2 5728.4 5780.7 5848.6 5899.4 5900.3 5904.2 5922.2 5365.9 5585.9 5601.8 5843.0 5844.2 5472.2 5491.1 5517.0 5558.6 5588.4 21911.0 22651.2 22802.3 22934.5 23021.9 2256.1 2381.6 2399.3 2399.6 2412.5 10384.7 10479.7 10534.9 10543.5 10900.4 2022.2 2056.1 2184.3 2231.2 2304.9 74901.0 79951.2 82355.8 82800.0 83141.2 11106.9 11414.0 11551.1 11663.5 11858.1 16205.6 16268.8 16468.1 16491.8 16502.3 8535.4 8541.5 8563.3 8629.2 8629.7 8147.8 8188.7 8199.5 8256.5 8270.1 5037.7 5094.4 5136.2 5146.4 5196.2 1942.8 2062.5 2125.5 2133.8 2159.1 968.3 1088.5 1090.8 1102.0 1126.1 18187.5 18355.2 18364.3 19365.5 19903.5 1018.5 1049.5 1075.2 1095.1 1099.8 10560.5 10898.3 10901.8 10908.0 10929.3 525596.0 527114.0 527376.0 535826.0 537694.0 90046.5 94171.5 95433.5 95621.5 95634.5 1084.9 1092.7 1098.6 1105.4 1119.7 16738.9 16957.1 17152.8 17153.0 17214.0 25037.5 38427.4 38795.8 40484.4 40562.6 56200.2 56246.0 56764.7 56826.1 57440.0 8783.2 8826.0 8990.5 9008.6 9025.2 9730.0 12759.8 12883.5 12905.5 12948.0 11399.1 11421.2 11446.3 11573.9 11879.6 11060.9 11173.8 11210.9 11291.5 11589.6 11802.4 11825.6 11850.9 11922.7 12104.1 8548.9 8721.8 8740.1 8824.2 8864.8 11827.8 12320.1 12364.5 12479.6 12545.6 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | [#3] mootools.html Cycles:180 Average:5745.54 Median:5016.26 stddev:3177.43 (63.3%) stddev-sans-first:3170.78 12:42:08 INFO - PROCESS | 6458 | Values: 1561.7 1564.7 1767.3 1875.9 1876.9 8441.2 8509.3 8589.6 8708.3 8731.1 8675.6 8730.9 8773.8 8782.8 8810.0 2433.1 2462.5 2467.4 2467.8 2473.4 2408.5 2488.1 2490.8 2492.2 2493.9 2442.5 2468.4 2470.4 2472.6 2473.8 2425.9 2433.7 2439.9 2441.6 2458.4 2348.4 2351.6 2352.0 2355.4 2373.8 8573.8 8611.3 8644.7 8644.9 8651.2 5528.5 5592.2 5632.6 5655.2 5657.4 3482.3 3488.9 3537.4 3610.6 3631.5 1156.4 1174.8 1181.8 1209.3 1210.9 11947.3 12014.8 12033.3 12086.5 12092.7 4887.2 4887.5 5010.4 5111.9 5115.9 10358.5 10395.4 10423.6 10424.1 10437.5 5517.9 5561.6 5576.6 5599.1 5616.1 4781.8 4886.2 4951.4 4954.0 4962.5 3966.9 4018.8 4061.3 4159.9 4174.7 1140.0 1231.3 1232.3 1234.1 1241.3 928.4 989.0 999.3 1045.1 1054.4 5902.5 5918.3 5951.0 5982.8 6137.4 495.9 500.7 506.3 507.1 508.1 3979.0 4011.0 4018.5 4050.5 4061.0 10507.8 10760.6 10849.6 10883.5 10917.6 10710.9 10720.6 10721.5 10735.7 10768.3 7783.2 8026.1 8048.2 8074.4 8095.0 5950.7 6009.0 6075.3 6141.2 6170.4 9381.9 9519.3 9530.5 9681.1 9705.9 9071.8 9229.3 9649.0 9697.5 9749.6 7424.2 7465.8 7572.4 7585.2 7594.1 8166.0 8173.1 8196.0 8231.2 8275.7 7356.9 7364.6 7464.9 7589.7 7641.3 6981.5 7000.6 7055.0 7089.1 7210.0 6885.4 6900.9 6912.8 6922.7 6950.2 4765.5 5017.2 5063.8 5094.9 5102.9 4930.4 4992.4 5015.3 5060.7 5076.8 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | [#4] prototype.html Cycles:180 Average:5793.95 Median:6695.48 stddev:1794.54 (26.8%) stddev-sans-first:1752.53 12:42:08 INFO - PROCESS | 6458 | Values: 355.3 362.5 363.4 368.3 368.7 6369.6 6575.7 6621.2 6637.5 6681.5 6421.8 6547.0 6561.1 6563.4 6564.8 4780.7 4886.6 4954.3 4956.9 4965.2 4850.4 4883.1 4885.2 4898.2 4905.7 4811.8 4812.2 4814.8 4821.5 4822.9 4816.3 4821.7 4823.3 4825.4 4836.7 4157.0 4168.2 4168.7 4175.0 4175.8 6283.3 6294.3 6294.5 6298.0 6302.8 5569.8 5622.8 5713.8 5718.4 5719.9 6812.5 6837.8 6839.8 6850.0 6855.2 4710.7 4712.4 4716.3 4716.6 4719.9 7134.1 7191.6 7224.0 7234.1 7244.2 4796.9 4820.1 4820.8 4826.1 4827.1 4727.6 4829.8 4835.0 4838.5 4854.9 5644.8 5663.2 5701.4 5717.1 5718.3 6941.2 6952.4 6953.0 6960.2 6966.4 1707.2 1730.4 1732.5 1736.1 1736.7 1095.6 1123.1 1137.1 1139.8 1151.8 6765.6 6770.6 6777.1 6778.1 6779.0 7159.1 7164.4 7168.4 7170.8 7176.0 6675.9 6770.0 6770.8 6818.2 6859.5 3873.6 3907.1 3910.7 3911.9 3922.7 7606.0 7609.2 7614.8 7623.8 7635.5 7329.0 7335.0 7343.4 7356.7 7363.6 7461.1 7578.8 7596.2 7599.9 7601.8 7144.0 7175.9 7200.9 7201.9 7211.1 7074.0 7077.4 7083.8 7088.4 7112.0 7533.0 7564.1 7580.2 7592.1 7594.8 4720.7 4789.7 4859.3 4862.3 4874.2 7155.7 7195.8 7198.3 7209.6 7219.1 6976.6 6994.7 6997.3 7002.8 7010.4 6938.0 6983.9 6990.0 6992.0 7003.6 6709.5 6798.8 6812.5 6831.7 6841.4 6976.6 6982.9 6985.2 7035.7 7041.2 6839.0 6840.9 6840.9 6847.5 6854.5 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - PROCESS | 6458 | [#5] yui.html Cycles:180 Average:15653.12 Median:47240.53 stddev:18144.41 (38.4%) stddev-sans-first:18161.58 12:42:08 INFO - PROCESS | 6458 | Values: 922.7 929.4 938.4 944.1 946.7 14629.8 14683.3 14803.5 14831.5 15017.2 14074.6 14190.4 14231.9 14242.1 14322.1 917.9 927.8 929.7 953.1 954.5 911.9 926.9 927.8 928.7 930.6 954.0 964.7 966.3 966.8 967.5 950.5 962.3 962.4 962.7 963.8 706.4 706.8 710.0 711.0 711.0 15663.2 15697.3 15706.3 15753.0 15763.9 14711.5 14766.5 14927.2 15060.7 15128.7 11691.1 12086.6 12213.9 12343.8 12423.6 3979.9 3999.8 4064.8 4086.3 4111.6 57399.1 57950.7 58084.7 59508.4 59800.0 11023.4 11068.3 11080.6 11364.4 11391.0 20847.3 20915.7 20957.9 20964.7 21028.0 8644.1 8735.3 8761.0 8775.3 8834.2 5129.9 5166.6 5216.2 5220.4 5234.5 3673.0 3749.3 3772.0 3789.2 3834.0 684.2 684.9 690.8 691.8 699.4 745.2 770.1 771.7 778.8 785.1 8670.2 8688.3 8706.5 8864.3 9633.9 736.5 741.0 748.8 755.3 755.7 4393.5 4447.0 4497.9 4504.8 4550.5 64128.0 64178.7 64259.0 64496.0 64732.0 58260.4 59058.0 59136.0 59601.7 59714.7 50232.0 50255.2 52296.7 52612.2 52708.0 8512.0 8612.1 8616.7 8885.4 8981.5 37873.6 37942.2 38657.2 39428.0 39680.6 45867.3 48613.8 48930.5 49532.3 49939.0 10957.8 10993.8 11077.5 11357.9 11379.8 12397.3 12438.6 12526.2 12544.8 12724.3 12008.6 13444.8 13475.7 13519.9 14041.3 11854.3 12718.0 13364.9 13393.9 13451.8 12924.0 13788.4 13906.3 14126.4 14247.8 11214.4 11595.7 11676.5 11701.5 11705.6 11681.6 11693.5 11793.2 11898.8 11917.6 12:42:08 INFO - PROCESS | 6458 | -------- Summary: end -------- 12:42:08 INFO - PROCESS | 6458 | 12:42:08 INFO - TEST-INFO | 6458: exit 0 12:42:08 INFO - TEST-OK | dromaeo_css | took 1109056ms 12:42:08 INFO - TEST-START | kraken 12:42:08 INFO - operating with platform_type : linux_ 12:42:08 INFO - Initialising browser for kraken test... 12:42:08 INFO - TEST-INFO | started process 6697 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcMuGfy/profile http://localhost:32948/getInfo.html) 12:42:12 INFO - PROCESS | 6697 | 12:42:12 INFO - PROCESS | 6697 | (process:6781): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 12:42:13 INFO - PROCESS | 6697 | __metrics Screen width/height:1600/1200 12:42:13 INFO - PROCESS | 6697 | colorDepth:24 12:42:13 INFO - PROCESS | 6697 | Browser inner width/height: 1024/697 12:42:13 INFO - PROCESS | 6697 | __metrics 12:42:14 ERROR - PROCESS | 6697 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 12:42:14 INFO - TEST-INFO | 6697: exit 0 12:42:14 INFO - Browser initialized. 12:42:14 INFO - Running cycle 1/1 for kraken test... 12:42:14 INFO - TEST-INFO | started process 6831 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcMuGfy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:42:16 INFO - PROCESS | 6831 | 12:42:16 INFO - PROCESS | 6831 | (process:6901): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 12:42:26 INFO - PROCESS | 6831 | RSS: Main: 151670784 12:42:26 INFO - PROCESS | 6831 | 12:42:29 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32948/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:30 INFO - PROCESS | 6831 | RSS: Main: 156946432 12:42:30 INFO - PROCESS | 6831 | 12:42:32 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32948/tests/kraken/driver.html?testName=audio-dft) 12:42:33 INFO - PROCESS | 6831 | RSS: Main: 156491776 12:42:33 INFO - PROCESS | 6831 | 12:42:36 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32948/tests/kraken/driver.html?testName=audio-fft) 12:42:36 INFO - PROCESS | 6831 | RSS: Main: 157237248 12:42:36 INFO - PROCESS | 6831 | 12:42:38 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32948/tests/kraken/driver.html?testName=audio-oscillator) 12:42:39 INFO - PROCESS | 6831 | RSS: Main: 156803072 12:42:39 INFO - PROCESS | 6831 | 12:42:41 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32948/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:42 INFO - PROCESS | 6831 | RSS: Main: 156962816 12:42:42 INFO - PROCESS | 6831 | 12:42:45 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32948/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:45 INFO - PROCESS | 6831 | RSS: Main: 156999680 12:42:45 INFO - PROCESS | 6831 | 12:42:49 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32948/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:49 INFO - PROCESS | 6831 | RSS: Main: 157323264 12:42:49 INFO - PROCESS | 6831 | 12:42:52 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32948/tests/kraken/driver.html?testName=json-parse-financial) 12:42:53 INFO - PROCESS | 6831 | RSS: Main: 156446720 12:42:53 INFO - PROCESS | 6831 | 12:42:54 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32948/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:42:55 INFO - PROCESS | 6831 | RSS: Main: 156139520 12:42:55 INFO - PROCESS | 6831 | 12:42:57 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:42:57 INFO - PROCESS | 6831 | RSS: Main: 156172288 12:42:57 INFO - PROCESS | 6831 | 12:42:59 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:00 INFO - PROCESS | 6831 | RSS: Main: 156418048 12:43:00 INFO - PROCESS | 6831 | 12:43:02 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:43:03 INFO - PROCESS | 6831 | RSS: Main: 157487104 12:43:03 INFO - PROCESS | 6831 | 12:43:06 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:43:06 INFO - PROCESS | 6831 | RSS: Main: 157265920 12:43:06 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | Cycle 1(1): loaded http://localhost:32948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32948/tests/kraken/driver.html?testName=ai-astar) 12:43:08 INFO - PROCESS | 6831 | RSS: Main: 158801920 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | __start_tp_report 12:43:08 INFO - PROCESS | 6831 | _x_x_mozilla_page_load 12:43:08 INFO - PROCESS | 6831 | _x_x_mozilla_page_load_details 12:43:08 INFO - PROCESS | 6831 | |i|pagename|runs| 12:43:08 INFO - PROCESS | 6831 | |0;ai-astar;111;113;105;110;111;112;101;110;114;110 12:43:08 INFO - PROCESS | 6831 | |1;audio-beat-detection;158;125;164;176;127;161;157;128;194;132 12:43:08 INFO - PROCESS | 6831 | |2;audio-dft;162;162;162;161;161;161;160;161;162;161 12:43:08 INFO - PROCESS | 6831 | |3;audio-fft;76;76;76;167;73;77;77;77;151;77 12:43:08 INFO - PROCESS | 6831 | |4;audio-oscillator;92;92;154;93;93;93;154;93;93;154 12:43:08 INFO - PROCESS | 6831 | |5;imaging-gaussian-blur;117;117;117;119;117;117;117;117;117;117 12:43:08 INFO - PROCESS | 6831 | |6;imaging-darkroom;146;146;146;146;146;147;147;146;145;146 12:43:08 INFO - PROCESS | 6831 | |7;imaging-desaturate;95;96;95;97;95;95;96;96;96;95 12:43:08 INFO - PROCESS | 6831 | |8;json-parse-financial;59;57;57;56;56;59;58;72;56;61 12:43:08 INFO - PROCESS | 6831 | |9;json-stringify-tinderbox;59;58;58;58;59;59;61;58;59;59 12:43:08 INFO - PROCESS | 6831 | |10;stanford-crypto-aes;72;75;80;74;82;74;74;74;74;75 12:43:08 INFO - PROCESS | 6831 | |11;stanford-crypto-ccm;119;117;123;121;124;116;125;113;134;133 12:43:08 INFO - PROCESS | 6831 | |12;stanford-crypto-pbkdf2;168;163;171;166;163;166;170;162;161;161 12:43:08 INFO - PROCESS | 6831 | |13;stanford-crypto-sha256-iterative;72;77;80;68;71;67;68;69;68;68 12:43:08 INFO - PROCESS | 6831 | __end_tp_report 12:43:08 INFO - PROCESS | 6831 | __start_cc_report 12:43:08 INFO - PROCESS | 6831 | _x_x_mozilla_cycle_collect,828 12:43:08 INFO - PROCESS | 6831 | __end_cc_report 12:43:08 INFO - PROCESS | 6831 | __startTimestamp1466019788777__endTimestamp 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | ------- Summary: start ------- 12:43:08 INFO - PROCESS | 6831 | Number of tests: 14 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.89 (3.5%) stddev-sans-first:4.10 12:43:08 INFO - PROCESS | 6831 | Values: 111.0 113.0 105.0 110.0 111.0 112.0 101.0 110.0 114.0 110.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#1] audio-beat-detection Cycles:10 Average:152.20 Median:159.50 stddev:23.47 (14.7%) stddev-sans-first:24.79 12:43:08 INFO - PROCESS | 6831 | Values: 158.0 125.0 164.0 176.0 127.0 161.0 157.0 128.0 194.0 132.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 12:43:08 INFO - PROCESS | 6831 | Values: 162.0 162.0 162.0 161.0 161.0 161.0 160.0 161.0 162.0 161.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#3] audio-fft Cycles:10 Average:92.70 Median:76.50 stddev:35.17 (46.0%) stddev-sans-first:36.78 12:43:08 INFO - PROCESS | 6831 | Values: 76.0 76.0 76.0 167.0 73.0 77.0 77.0 77.0 151.0 77.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#4] audio-oscillator Cycles:10 Average:111.10 Median:93.00 stddev:29.61 (31.8%) stddev-sans-first:30.59 12:43:08 INFO - PROCESS | 6831 | Values: 92.0 92.0 154.0 93.0 93.0 93.0 154.0 93.0 93.0 154.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 12:43:08 INFO - PROCESS | 6831 | Values: 117.0 117.0 117.0 119.0 117.0 117.0 117.0 117.0 117.0 117.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 12:43:08 INFO - PROCESS | 6831 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 145.0 146.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 12:43:08 INFO - PROCESS | 6831 | Values: 95.0 96.0 95.0 97.0 95.0 95.0 96.0 96.0 96.0 95.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:58.50 stddev:4.82 (8.2%) stddev-sans-first:5.11 12:43:08 INFO - PROCESS | 6831 | Values: 59.0 57.0 57.0 56.0 56.0 59.0 58.0 72.0 56.0 61.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.92 (1.6%) stddev-sans-first:0.97 12:43:08 INFO - PROCESS | 6831 | Values: 59.0 58.0 58.0 58.0 59.0 59.0 61.0 58.0 59.0 59.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:74.50 stddev:3.10 (4.2%) stddev-sans-first:3.03 12:43:08 INFO - PROCESS | 6831 | Values: 72.0 75.0 80.0 74.0 82.0 74.0 74.0 74.0 74.0 75.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:123.50 stddev:6.90 (5.6%) stddev-sans-first:7.20 12:43:08 INFO - PROCESS | 6831 | Values: 119.0 117.0 123.0 121.0 124.0 116.0 125.0 113.0 134.0 133.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:166.00 stddev:3.67 (2.2%) stddev-sans-first:3.73 12:43:08 INFO - PROCESS | 6831 | Values: 168.0 163.0 171.0 166.0 163.0 166.0 170.0 162.0 161.0 161.0 12:43:08 INFO - PROCESS | 6831 | 12:43:08 INFO - PROCESS | 6831 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:70.00 stddev:4.39 (6.3%) stddev-sans-first:4.64 12:43:08 INFO - PROCESS | 6831 | Values: 72.0 77.0 80.0 68.0 71.0 67.0 68.0 69.0 68.0 68.0 12:43:08 INFO - PROCESS | 6831 | -------- Summary: end -------- 12:43:08 INFO - PROCESS | 6831 | 12:43:09 INFO - TEST-INFO | 6831: exit 0 12:43:09 INFO - TEST-OK | kraken | took 60993ms 12:43:09 INFO - SUITE-END | took 1170s 12:43:09 INFO - Completed test suite (00:19:30) 12:43:09 INFO - javascript benchmark 12:43:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.082302366849, 3389.1188291044327, 3399.371116343475, 3433.690967812895, 3435.519027336172, 5633.63381380211, 5653.4911857932775, 5658.264425624673, 5659.4340565943385, 5697.829065128048, 5648.971755141219, 5658.632188907718, 5658.660480371181, 5660.179274005267, 5664.830055098343, 5113.693178409298, 5147.691138531682, 5151.227315902607, 5178.741062946857, 5196.662216955905, 5145.3568303962, 5203.817866374678, 5274.340707411574, 5275.973620131894, 5290.206469029637, 5197.376314842224, 5258.815941442051, 5288.312519372476, 5296.152615581508, 5309.920351194735, 5256.737163141827, 5263.052650522885, 5269.894602107936, 5271.103912334874, 5271.235670827708, 5038.244263360489, 5105.5149760772665, 5177.922331165036, 5226.451222621608, 5237.266782650432, 5543.113101903676, 5566.081596536566, 5629.971850140723, 5642.548596112301, 5686.289213848269, 5994.220751302347, 6031.969840150815, 6034.24571928509, 6036.849078773022, 6039.8792024159275, 6035.245594300713, 6112.541559383019, 6114.724837382329, 6128.961487065919, 6129.846753831145, 5749.453801888814, 5759.510441612426, 5760.193572899798, 5769.8557536061935, 5808.477237048686, 6256.499480041586, 6290.24517057956, 6299.401556852092, 6302.779402720883, 6314.179156709598, 5921.881562368814, 5987.550933679991, 6044.032954727222, 6060.818175454743, 6071.241094863142, 6129.695011270001, 6134.159752074449, 6141.188349436927, 6145.262568491809, 6152.753889844356, 6049.1833602463985, 6054.576179667469, 6110.083487476914, 6120.663363515049, 6141.84645383869, 5980.85047873798, 6074.179985701874, 6124.785632502841, 6136.3965394158, 6139.216166136355, 4660.53394660538, 4665.160271151158, 4678.625709943196, 4689.60138388234, 4692.976535117371, 4179.6029377209115, 4180.393842892763, 4185.832566697359, 4188.183304255641, 4203.810828512725, 5748.741306641211, 5854.531637468992, 5964.552658550626, 5982.46157845796, 6017.819465416044, 6130.703893784946, 6242.937570624235, 6270.404311590391, 6270.529710271748, 6273.37266273369, 5836.503897168704, 5847.210626565445, 5869.735861886225, 5870.471657550841, 5901.468867801918, 5081.28861959326, 5092.592592592584, 5099.260607211933, 5105.2086926526445, 5119.974400127976, 6394.0, 6431.839204019843, 6435.549511534147, 6443.130177425987, 6447.355264473609, 6088.0, 6116.235470566179, 6152.2002139722445, 6165.72254248551, 6179.258488981351, 6327.810165695037, 6329.0, 6347.270063942521, 6383.93616063852, 6384.201974753156, 5929.377415371324, 5988.520918326436, 5989.0, 6005.489533389536, 6009.0, 5740.540756739534, 6076.969615151896, 6082.330943596289, 6126.15126988545, 6167.5682702210415, 5975.760969561169, 5992.460678538952, 6027.065804800263, 6035.6076855004285, 6036.607620504653, 5872.412758724265, 6029.57792954489, 6029.728662210299, 6035.78874739382, 6036.0, 5768.423157684366, 5870.765169393177, 5882.441168089024, 5940.46535811779, 5984.162217289496, 5936.050231962865, 5947.197128387786, 5949.762009519571, 5956.344802071855, 5959.553033522417, 5785.450382213683, 5809.796657116981, 5810.883782324246, 5819.854503637443, 5820.883582328245], "value": 5640.958849597288, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.350308449262, 682.8668409660116, 700.9964950175248, 731.5939653492313, 742.6138408027824, 31539.369212615762, 32195.51706724401, 39074.60925390745, 39615.80192099042, 39790.602093979054, 42634.78682606583, 43073.7846310768, 43282.78358608203, 43398.56601433985, 43420.3486947696, 8821.69124080659, 8990.865137022949, 9006.144416280476, 9006.279497640206, 9012.729618111467, 8900.243479304234, 8929.241014513775, 8980.41627294227, 8991.685291014815, 9013.36906416551, 9305.255579553654, 9325.860112098324, 9327.067293270653, 9359.20446762026, 9413.576389062475, 9277.396969197014, 9322.580483878208, 9339.673111441101, 9349.39228950116, 9363.25093992479, 8783.692570760057, 8845.115488451167, 8850.690225842129, 8873.467591944504, 8880.467171969638, 43471.56528434707, 43711.12577748459, 44051.559484405065, 44219.77890110561, 44227.557724422986, 3819.0452386903276, 3932.4494570760116, 4463.553644635543, 4551.294549344857, 4579.885502862422, 32740.0, 33158.6684133158, 33438.0, 34055.65944340575, 34588.30823383518, 4454.9777251113865, 4492.415985921842, 4506.909861802746, 4532.093581283723, 4535.233507560385, 297025.0, 297428.0, 297797.0, 298437.0, 298442.0, 40153.79923100366, 40791.18417631631, 64578.354216457235, 65048.34951650518, 65102.34897651058, 40276.194476110315, 40311.3953290695, 40475.59524404718, 40568.594314057074, 40646.59353406487, 14985.475508357154, 15053.774193387108, 15059.774103388458, 15201.467948621745, 15288.847111528965, 13255.403506842214, 13315.267660278583, 13321.467141314239, 13339.533116340881, 13344.733105338033, 9625.278104142217, 9663.082007209305, 9682.9031709682, 9714.077162669535, 9727.610895564098, 3638.890833275006, 3680.5215322007966, 3749.9625003750157, 3760.4359346098304, 3762.6425489578446, 1152.590830255264, 1169.0997931592626, 1179.2193787381116, 1182.9349385783864, 1214.8056310990198, 32633.836830815693, 33507.49738753921, 33773.83113084419, 33999.32001359959, 34608.48087278693, 1182.9231099978474, 1198.161287099034, 1232.5254776910954, 1260.3950103950156, 1267.0876968582606, 17468.301267949395, 17634.647307054045, 17686.09060067856, 17746.5563360917, 17778.5555361117, 463180.0, 526373.0, 531011.0, 535650.286994258, 543331.0, 58441.707791461624, 59244.70377648171, 59443.702781485816, 59474.40525594775, 59576.40423595709, 1423.2101183843067, 1545.412743157593, 1561.3988614383545, 1571.4892659885584, 1582.8733701303868, 33350.499742503394, 33501.66498334985, 34004.0, 34049.31901362009, 34233.144171395914, 56446.717766410904, 57302.0, 57367.42632573621, 57399.426005739406, 57454.0, 438095.0, 439063.0, 440469.0, 440605.0, 441830.0, 31525.527117092803, 47648.2852757202, 48262.27606585974, 48526.75736621294, 48906.26640600465, 45084.0, 45413.772931135136, 45964.54035459736, 46023.079538409715, 46636.53363466458, 40784.79607601943, 42588.36117458178, 42684.78657606692, 42947.35578966381, 44337.77831110824, 40879.795601023, 41037.38443923403, 41611.58388416077, 42258.36612450877, 42724.1455170901, 43878.78060609676, 44241.55758442375, 44329.11341773082, 44560.0, 44689.32966005448, 35372.29255414929, 36238.8188059058, 36365.27269454649, 37014.25971480609, 37645.435318470794, 29141.271468213465, 29443.0, 29556.261093471974, 29770.404591908475, 30009.0], "value": 19902.256593280254, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.93169092945124, 430.9138172365524, 433.5750964055224, 435.7929983257953, 436.4827679200147, 18743.90628046861, 19120.23519059236, 19318.22727090915, 19517.512062198435, 19604.411867643994, 18946.147423365946, 19064.42806715801, 19250.903745481253, 19383.127759250834, 19422.22311107554, 5482.122860342346, 5660.9716951415285, 5734.79928202513, 5734.971325143369, 5927.2590926134235, 3978.6817054635703, 4151.8962025949295, 5686.232358631573, 5728.398518155596, 5780.653160810344, 5848.619839710405, 5899.439553242457, 5900.291964964189, 5904.1734157218025, 5922.170896074553, 5365.892682146336, 5585.916211256835, 5601.80393686219, 5843.0, 5844.211031510734, 5472.179173124024, 5491.121420572689, 5516.972415137899, 5558.638688485235, 5588.4411558844195, 21911.0, 22651.20720774765, 22802.315930522112, 22934.54130917389, 23021.884890575606, 2256.142665787007, 2381.5594115088766, 2399.3281881073326, 2399.6040653292184, 2412.541617092747, 10384.740381490448, 10479.738006549896, 10534.947325263402, 10543.525541350658, 10900.400477973708, 2022.2113375783456, 2056.1364227024687, 2184.3337781976493, 2231.2079211879714, 2304.9423764405856, 74901.0, 79951.20048799495, 82355.81427802407, 82800.0, 83141.16858831394, 11106.888931110747, 11414.0, 11551.075913926865, 11663.533458661559, 11858.110641701907, 16205.594860128354, 16268.75596866048, 16468.09425481586, 16491.83508164903, 16502.33990640361, 8535.359848011423, 8541.53021583819, 8563.314934805201, 8629.22336989674, 8629.654813807378, 8147.796305092302, 8188.707161994651, 8199.508029518249, 8256.463329883538, 8270.131636178236, 5037.722925239073, 5094.4141423736, 5136.203888397305, 5146.433892271847, 5196.168613021974, 1942.786293507713, 2062.4946888012532, 2125.5111324395475, 2133.775953524889, 2159.1147629472157, 968.3076600230869, 1088.50473034769, 1090.7654854206385, 1101.983470247947, 1126.1441304608393, 18187.45437636873, 18355.174017169258, 18364.265429382944, 19365.51586210356, 19903.502412439804, 1018.5467466977124, 1049.506731836036, 1075.172117469544, 1095.1239008793093, 1099.8130317845985, 10560.524776384927, 10898.291611045257, 10901.781964360669, 10908.0, 10929.289596176382, 525596.0, 527114.0, 527376.0, 535826.0, 537694.0, 90046.5403050974, 94171.52914235384, 95433.5228323854, 95621.5218923901, 95634.52182739042, 1084.8698156221303, 1092.6612750047511, 1098.5880294889416, 1105.3699391346854, 1119.7424592343807, 16738.916305418395, 16957.06736129525, 17152.828471715624, 17152.970821750736, 17214.0, 25037.499250014534, 38427.42358864675, 38795.80602097084, 40484.3927341096, 40562.59437405588, 56200.15699764425, 56246.0, 56764.71617641886, 56826.14760778674, 57440.0, 8783.165599268059, 8825.955870220609, 8990.460572365679, 9008.639654414012, 9025.232855207381, 9730.0, 12759.808602870778, 12883.549075782303, 12905.548305809252, 12948.028897832512, 11399.14506412039, 11421.200515964136, 11446.255993360404, 11573.942130289295, 11879.643610691694, 11060.944695276472, 11173.832392514281, 11210.88789112131, 11291.491882865126, 11589.594364197214, 11802.350870702194, 11825.645230643095, 11850.940745296219, 11922.701932451759, 12104.083130683124, 8548.914510854811, 8721.781955450911, 8740.082291359317, 8824.161704638049, 8864.778380540538, 11827.82258266144, 12320.137590368588, 12364.505419783109, 12479.56321528742, 12545.623631291075], "value": 9380.126387174354, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.6720488697374, 1564.7339952208124, 1767.3372485318005, 1875.8968256745873, 1876.8738756745945, 8441.240288374045, 8509.319254459644, 8589.613467393967, 8708.259797917177, 8731.126887311266, 8675.60959756811, 8730.86903696445, 8773.780655483617, 8782.824343513126, 8809.95595022024, 2433.0997530913587, 2462.4705688277018, 2467.4078221226864, 2467.7902378297867, 2473.4311108445067, 2408.494216214597, 2488.054539275073, 2490.8380955237944, 2492.202495201527, 2493.9002439902383, 2442.4870777136734, 2468.3582268610203, 2470.3947532854477, 2472.6043832986725, 2473.7649923257263, 2425.890834912433, 2433.6836211292507, 2439.85360878347, 2441.584930561809, 2458.397692565324, 2348.4481146930457, 2351.58847201739, 2351.9886448826996, 2355.422921384272, 2373.8338316317863, 8573.828523429496, 8611.26804221642, 8644.740657780276, 8644.870326945102, 8651.178138076873, 5528.530074943635, 5592.217089607459, 5632.633878797906, 5655.208270842086, 5657.43425657431, 3482.2687235680537, 3488.9302213955834, 3537.4340105582983, 3610.6389361063684, 3631.473436351714, 1156.4044517073714, 1174.7591743692608, 1181.7754626621002, 1209.3046498263534, 1210.878912108791, 11947.283163010246, 12014.759704805854, 12033.338166400845, 12086.516539338416, 12092.697682557919, 4887.169181239198, 4887.462379138292, 5010.448850626428, 5111.923321150186, 5115.923261151086, 10358.53386597605, 10395.376277423378, 10423.583056677648, 10424.061834434937, 10437.530311136017, 5517.889642207214, 5561.63849349791, 5576.553875689935, 5599.076152434803, 5616.073347897633, 4781.832635857728, 4886.169351210303, 4951.430585482643, 4953.975230123826, 4962.528559786814, 3966.900827479336, 4018.819153138116, 4061.289274376972, 4159.916801664011, 4174.707770456039, 1139.9886001140048, 1231.2735486063086, 1232.3222227774688, 1234.0744441668749, 1241.2800575666092, 928.4104593583123, 988.951711186145, 999.2905037423511, 1045.11165509318, 1054.4411461925192, 5902.468777810018, 5918.260217472816, 5950.958582248089, 5982.790602328898, 6137.447629713347, 495.86611614864506, 500.73961539999, 506.3164727617687, 507.11255303219366, 508.0879820721813, 3979.0, 4010.979945100256, 4018.477597912253, 4050.5139383274195, 4061.0, 10507.789844203073, 10760.623378181726, 10849.566017359375, 10883.510242039127, 10917.617883374203, 10710.892891071146, 10720.624778132727, 10721.517531711092, 10735.677929662123, 10768.3000604962, 7783.182765809622, 8026.117127116013, 8048.235417635316, 8074.434789564792, 8094.959525202336, 5950.702464876652, 6008.969955150196, 6075.27096748393, 6141.232345956755, 6170.413810687977, 9381.906180938104, 9519.333646644669, 9530.475823829756, 9681.128698417177, 9705.9029409705, 9071.818563628822, 9229.307801915018, 9649.0, 9697.466639334903, 9749.610015599297, 7424.220456852169, 7465.776026719207, 7572.39420846342, 7585.241475852371, 7594.126675432396, 8166.0, 8173.141820108803, 8195.95902020486, 8231.218034286732, 8275.710350137717, 7356.926430735838, 7364.631768411665, 7464.888026679496, 7589.696412143452, 7641.312281894656, 6981.511294209559, 7000.61496617691, 7055.047568578177, 7089.149302083783, 7210.0, 6885.449164066963, 6900.861982760216, 6912.792616221522, 6922.723091076301, 6950.165980082423, 4765.475797662185, 5017.1721665924715, 5063.797448102182, 5094.872628184176, 5102.872428189325, 4930.408350997904, 4992.425871024879, 5015.3480047593575, 5060.746962651926, 5076.771545280545], "value": 4593.413316410311, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.2539666699929, 362.46174430970007, 363.4112737365468, 368.26714837473435, 368.70135190495716, 6369.6496692681885, 6575.7369705211795, 6621.205455345354, 6637.535372523926, 6681.4654827613795, 6421.784484352903, 6547.0, 6561.0814485972005, 6563.409293163614, 6564.770233041844, 4780.665353425262, 4886.584640305578, 4954.331165292684, 4956.925646115311, 4965.180745177059, 4850.369451971239, 4883.12103821312, 4885.218365061591, 4898.24077268024, 4905.730184839832, 4811.78346974387, 4812.157872372339, 4814.796300924769, 4821.4696383397795, 4822.903541929177, 4816.277558366256, 4821.734804585764, 4823.348847905523, 4825.372701548794, 4836.709797412166, 4156.958430415687, 4168.2080404723, 4168.749875007509, 4175.0, 4175.812088456027, 6283.3088360280335, 6294.339094395114, 6294.527910406692, 6297.992321228628, 6302.810915672491, 5569.832905012856, 5622.803201887955, 5713.771449142029, 5718.42815718429, 5719.857003574903, 6812.48906332027, 6837.794866154023, 6839.794806155824, 6850.0, 6855.177378714586, 4710.74090924999, 4712.387389639325, 4716.339712440261, 4716.551927566876, 4719.858404247878, 7134.072570565793, 7191.568505889663, 7224.024756657836, 7234.095738032746, 7244.203137654854, 4796.880077998078, 4820.084184005028, 4820.783064762024, 4826.0830442215865, 4827.131116399082, 4727.621790256771, 4829.782659780248, 4834.95165048345, 4838.467768545457, 4854.927176092361, 5644.8024319148635, 5663.178839068312, 5701.4298570142655, 5717.142428635655, 5718.25662663851, 6941.201761797458, 6952.409045231212, 6953.0, 6960.164780226405, 6966.407855332252, 1707.1976171199517, 1730.3857130718666, 1732.523556022104, 1736.0885535093923, 1736.6613510365357, 1095.6439157273758, 1123.1071298317693, 1137.0676045642495, 1139.7549526851767, 1151.7984352738238, 6765.594064356154, 6770.5599136056, 6777.051212830208, 6778.118844550177, 6779.0, 7159.069320988342, 7164.355208031198, 7168.426525877917, 7170.820729481805, 7175.96412017947, 6675.866482670416, 6770.0, 6770.830729231809, 6818.2159051708695, 6859.485538584527, 3873.6248631735893, 3907.0623050468253, 3910.7066969977363, 3911.9217615647526, 3922.666573341241, 7606.04924384452, 7609.239076092346, 7614.847703046019, 7623.847523049619, 7635.503692259986, 7328.963355183297, 7335.0, 7343.412526997828, 7356.668949897267, 7363.558186508827, 7461.067366579177, 7578.810529736801, 7596.164421913585, 7599.886001709979, 7601.809954751176, 7143.964280178566, 7175.856482870209, 7200.89198662031, 7201.927980720126, 7211.062561867134, 7074.0, 7077.433805295461, 7083.787486375417, 7088.397486213729, 7111.964440177766, 7533.0, 7564.092308922965, 7580.241975802375, 7592.126905405949, 7594.772156835304, 4720.693154944917, 4789.736564488987, 4859.319695242598, 4862.270659401118, 4874.171390863633, 7155.749548765768, 7195.784126476214, 7198.280171982759, 7209.603471808891, 7219.061522002106, 6976.5814051157095, 6994.685239164148, 6997.26528714498, 7002.824929376807, 7010.4041156500225, 6938.028675985467, 6983.860322793617, 6990.0, 6992.042228176118, 7003.6498175092065, 6709.496787740842, 6798.76204332846, 6812.455003599601, 6831.69257383409, 6841.418479429304, 6976.581405115507, 6982.895256571054, 6985.1617805863625, 7035.683394247373, 7041.155061392666, 6838.965805170942, 6840.9315906842285, 6840.9315906842285, 6847.452203823782, 6854.485913556603], "value": 5207.091373059215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [922.6862866625346, 929.3866048408051, 938.4322484896637, 944.0889541592362, 946.7017889364845, 14629.780553291695, 14683.26583670815, 14803.481878134267, 14831.480898168566, 15017.249137543135, 14074.64813379666, 14190.43238270468, 14231.857681423182, 14242.145471271706, 14322.069065510734, 917.8531434970406, 927.7587827164951, 929.7210836748982, 953.142172045158, 954.4654993203793, 911.9042500537447, 926.9026752191024, 927.772695689557, 928.7074571509986, 930.6230976454547, 953.9761505962372, 964.720231132974, 966.3284017607765, 966.7873067925045, 967.5065716484609, 950.4629884115487, 962.345604988604, 962.3936919740519, 962.711186644011, 963.7879666473365, 706.4136766483806, 706.752636577199, 710.0, 710.9644517774091, 711.0045935690024, 15663.216839157996, 15697.293621787047, 15706.293216805272, 15753.0, 15763.92118039414, 14711.485098021518, 14766.483173088998, 14927.179005154712, 15060.698786024328, 15128.697426051527, 11691.123165762518, 12086.576969806014, 12213.938930305292, 12343.814842777365, 12423.62729118128, 3979.900502487432, 3999.7800120993334, 4064.7561146331313, 4086.264472394968, 4111.568285330057, 57399.13901291484, 57950.7102464485, 58084.70957645227, 59508.40491595115, 59800.0, 11023.393713345682, 11068.335899846035, 11080.5567777288, 11364.431778411046, 11391.0, 20847.270345537832, 20915.68626470604, 20957.89521052385, 20964.685529717066, 21028.0, 8644.092370301154, 8735.25750311218, 8761.0, 8775.254103401283, 8834.160754728291, 5129.8717532062, 5166.6125040621355, 5216.165413533817, 5220.425753167148, 5234.502722241381, 3672.9632703672623, 3749.343864823645, 3772.000419888732, 3789.1663833956486, 3833.961660383416, 684.1926526698544, 684.9006894000343, 690.8169335126196, 691.8201267670341, 699.4299645788768, 745.1505283976271, 770.1412924589043, 771.7376092128703, 778.7585848386905, 785.0618510879558, 8670.176333248332, 8688.348373871984, 8706.52114133716, 8864.290856731446, 9633.915211970076, 736.4660621049696, 741.0, 748.7716246544857, 755.2636179724834, 755.6826133024033, 4393.51671316155, 4447.021655235843, 4497.865064048084, 4504.774761261924, 4550.453945526558, 64128.0, 64178.6791066051, 64259.0, 64496.0, 64732.0, 58260.41739582635, 59058.0, 59136.0, 59601.70199148977, 59714.70142649259, 50232.0, 50255.24617130746, 52296.738516307174, 52612.210816837774, 52708.0, 8512.0, 8612.138786121337, 8616.655333786579, 8885.422447541148, 8981.506017168856, 37873.62126378701, 37942.2411551773, 38657.22685546217, 39428.0, 39680.60319396885, 45867.311990319504, 48613.75693121512, 48930.51069489402, 49532.25701614551, 49939.0, 10957.780844382909, 10993.780124397626, 11077.501512431798, 11357.886421135683, 11379.829302560303, 12397.256164630151, 12438.564650237198, 12526.156216605708, 12544.811827822408, 12724.300163491096, 12008.63974080779, 13444.798328024892, 13475.73048539004, 13519.864801351861, 14041.297935103408, 11854.348010859485, 12718.0, 13364.93317533445, 13393.866061339262, 13451.798223026466, 12924.0, 13788.379522921694, 13906.304684765519, 14126.364313605707, 14247.786283205967, 11214.383208923588, 11595.652130436101, 11676.532938682358, 11701.473433695333, 11705.648830535098, 11681.591144309908, 11693.532258709556, 11793.233439826385, 11898.76202475963, 11917.642470725892], "value": 7193.697705634871, "unit": "score"}], "name": "dromaeo_css", "value": 7522.530892884192, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 105.0, 110.0, 111.0, 112.0, 101.0, 110.0, 114.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 125.0, 164.0, 176.0, 127.0, 161.0, 157.0, 128.0, 194.0, 132.0], "value": 152.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 161.0, 161.0, 161.0, 160.0, 161.0, 162.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 167.0, 73.0, 77.0, 77.0, 77.0, 151.0, 77.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 154.0, 93.0, 93.0, 93.0, 154.0, 93.0, 93.0, 154.0], "value": 111.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 119.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 97.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 57.0, 56.0, 56.0, 59.0, 58.0, 72.0, 56.0, 61.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 58.0, 59.0, 59.0, 61.0, 58.0, 59.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 80.0, 74.0, 82.0, 74.0, 74.0, 74.0, 74.0, 75.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 123.0, 121.0, 124.0, 116.0, 125.0, 113.0, 134.0, 133.0], "value": 122.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 163.0, 171.0, 166.0, 163.0, 166.0, 170.0, 162.0, 161.0, 161.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 80.0, 68.0, 71.0, 67.0, 68.0, 69.0, 68.0, 68.0], "value": 70.8, "unit": "score"}], "name": "kraken", "value": 1537.6, "alertThreshold": 2.0}]} 12:43:10 INFO - Return code: 0 12:43:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:43:10 ERROR - # TBPL SUCCESS # 12:43:10 INFO - Running post-action listener: _resource_record_post_action 12:43:10 INFO - Running post-run listener: _resource_record_post_run 12:43:11 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 495616; Write bytes: 231137280; Read time: 56; Write time: 231436 12:43:11 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 17043456; Read time: 0; Write time: 10268 12:43:11 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 495616; Write bytes: 214093824; Read time: 56; Write time: 221168 12:43:11 INFO - Running post-run listener: _upload_blobber_files 12:43:11 INFO - Blob upload gear active. 12:43:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:43:11 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 12:43:11 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:43:11 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:43:11 INFO - (blobuploader) - INFO - Open directory for files ... 12:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:43:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:12 INFO - (blobuploader) - INFO - Done attempting. 12:43:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:43:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:43:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:13 INFO - (blobuploader) - INFO - Done attempting. 12:43:13 INFO - (blobuploader) - INFO - Iteration through files over. 12:43:13 INFO - Return code: 0 12:43:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:43:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:43:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6528e13071eee923077b5cfc332481fb6f1e77992477688c1f9041b9b98857fcdd259ea2e3e78516e20e5a4ae1cb91018970509728cf36cab44877ad53990782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61cbec60569d977741d8f4ac46ca758332b19e3202e1ad309fb79c3ea5d0b9213e951443dc9e7d5a44855d2e3942ae438a029345a83c6aa525059e8caf0e956c"} 12:43:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:43:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:43:13 INFO - Contents: 12:43:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6528e13071eee923077b5cfc332481fb6f1e77992477688c1f9041b9b98857fcdd259ea2e3e78516e20e5a4ae1cb91018970509728cf36cab44877ad53990782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61cbec60569d977741d8f4ac46ca758332b19e3202e1ad309fb79c3ea5d0b9213e951443dc9e7d5a44855d2e3942ae438a029345a83c6aa525059e8caf0e956c"} 12:43:13 INFO - Running post-run listener: copy_logs_to_upload_dir 12:43:13 INFO - Copying logs to upload dir... 12:43:13 INFO - mkdir: /builds/slave/test/build/upload/logs 12:43:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.135931 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-06-15 12:43:13.575741) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-15 12:43:13.578885) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6528e13071eee923077b5cfc332481fb6f1e77992477688c1f9041b9b98857fcdd259ea2e3e78516e20e5a4ae1cb91018970509728cf36cab44877ad53990782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61cbec60569d977741d8f4ac46ca758332b19e3202e1ad309fb79c3ea5d0b9213e951443dc9e7d5a44855d2e3942ae438a029345a83c6aa525059e8caf0e956c"} build_url:https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011313 build_url: 'https://queue.taskcluster.net/v1/task/QCRCn4rQSNaPHOnxQEyKUA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6528e13071eee923077b5cfc332481fb6f1e77992477688c1f9041b9b98857fcdd259ea2e3e78516e20e5a4ae1cb91018970509728cf36cab44877ad53990782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61cbec60569d977741d8f4ac46ca758332b19e3202e1ad309fb79c3ea5d0b9213e951443dc9e7d5a44855d2e3942ae438a029345a83c6aa525059e8caf0e956c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-15 12:43:13.603621) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:43:13.603926) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1466015784.107616-453288258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 12:43:13.685372) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-15 12:43:13.685690) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-15 12:43:13.685973) ========= ========= Total master_lag: 0.15 =========