builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-067 starttime: 1462489953.41 results: success (0) buildid: 20160505133015 builduid: 13dfc1402c9b46ca8de3a97ddf5a0549 revision: e5a10bc7dac4ee2453d8319165c1f6578203eac7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.411207) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.411738) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.412089) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.430125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.430932) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-05 16:12:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 90.4M=0s 2016-05-05 16:12:33 (90.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.071267 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.520639) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.521309) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018447 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.573215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:33.574072) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e5a10bc7dac4ee2453d8319165c1f6578203eac7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e5a10bc7dac4ee2453d8319165c1f6578203eac7 --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=e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-05 16:12:33,755 truncating revision to first 12 chars 2016-05-05 16:12:33,755 Setting DEBUG logging. 2016-05-05 16:12:33,755 attempt 1/10 2016-05-05 16:12:33,755 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e5a10bc7dac4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 16:12:34,016 unpacking tar archive at: mozilla-central-e5a10bc7dac4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.532505 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:34.122503) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:34.122864) ========= script_repo_revision: e5a10bc7dac4ee2453d8319165c1f6578203eac7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:34.123311) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:34.123629) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 16:12:34.133480) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-05 16:12:34.133782) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:12:34 INFO - MultiFileLogger online at 20160505 16:12:34 in /builds/slave/test 16:12:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:12:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:34 INFO - {'append_to_log': False, 16:12:34 INFO - 'base_work_dir': '/builds/slave/test', 16:12:34 INFO - 'blob_upload_branch': 'Firefox', 16:12:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:34 INFO - 'branch': 'Firefox', 16:12:34 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:34 INFO - 'config_files': ('talos/linux_config.py',), 16:12:34 INFO - 'default_actions': ('clobber', 16:12:34 INFO - 'read-buildbot-config', 16:12:34 INFO - 'download-and-extract', 16:12:34 INFO - 'populate-webroot', 16:12:34 INFO - 'create-virtualenv', 16:12:34 INFO - 'install', 16:12:34 INFO - 'run-tests'), 16:12:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:34 INFO - 'download_minidump_stackwalk': True, 16:12:34 INFO - 'download_symbols': 'ondemand', 16:12:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:34 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:34 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:34 INFO - 'installer_path': 'installer.exe', 16:12:34 INFO - 'log_level': 'info', 16:12:34 INFO - 'log_name': 'talos', 16:12:34 INFO - 'log_to_console': True, 16:12:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:12:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:12:34 INFO - 'opt_config_files': (), 16:12:34 INFO - 'pip_index': False, 16:12:34 INFO - 'sps_profile': False, 16:12:34 INFO - 'sps_profile_interval': 0, 16:12:34 INFO - 'suite': 'dromaeojs-e10s', 16:12:34 INFO - 'system_bits': '32', 16:12:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:34 INFO - 'title': 'talos-linux64-ix-067', 16:12:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:34 INFO - 'use_talos_json': True, 16:12:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:34 INFO - 'work_dir': 'build'} 16:12:34 INFO - ##### 16:12:34 INFO - ##### Running clobber step. 16:12:34 INFO - ##### 16:12:34 INFO - Running pre-action listener: _resource_record_pre_action 16:12:34 INFO - Running main action method: clobber 16:12:34 INFO - rmtree: /builds/slave/test/build 16:12:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:35 INFO - Running post-action listener: _resource_record_post_action 16:12:35 INFO - ##### 16:12:35 INFO - ##### Running read-buildbot-config step. 16:12:35 INFO - ##### 16:12:35 INFO - Running pre-action listener: _resource_record_pre_action 16:12:35 INFO - Running main action method: read_buildbot_config 16:12:35 INFO - Using buildbot properties: 16:12:35 INFO - { 16:12:35 INFO - "project": "", 16:12:35 INFO - "product": "firefox", 16:12:35 INFO - "script_repo_revision": "production", 16:12:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:12:35 INFO - "repository": "", 16:12:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:12:35 INFO - "buildid": "20160505133015", 16:12:35 INFO - "pgo_build": "True", 16:12:35 INFO - "basedir": "/builds/slave/test", 16:12:35 INFO - "buildnumber": 23, 16:12:35 INFO - "slavename": "talos-linux64-ix-067", 16:12:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:12:35 INFO - "platform": "ubuntu64_hw", 16:12:35 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 16:12:35 INFO - "branch": "mozilla-central", 16:12:35 INFO - "repo_path": "mozilla-central", 16:12:35 INFO - "revision": "e5a10bc7dac4ee2453d8319165c1f6578203eac7", 16:12:35 INFO - "stage_platform": "linux64-pgo", 16:12:35 INFO - "builduid": "13dfc1402c9b46ca8de3a97ddf5a0549", 16:12:35 INFO - "slavebuilddir": "test" 16:12:35 INFO - } 16:12:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:12:35 INFO - Running post-action listener: _resource_record_post_action 16:12:35 INFO - ##### 16:12:35 INFO - ##### Running download-and-extract step. 16:12:35 INFO - ##### 16:12:35 INFO - Running pre-action listener: _resource_record_pre_action 16:12:35 INFO - Running main action method: download_and_extract 16:12:35 INFO - mkdir: /builds/slave/test/build/tests 16:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:35 INFO - https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:12:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:12:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:12:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:12:36 INFO - Downloaded 1361 bytes. 16:12:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:12:36 INFO - Using the following test package requirements: 16:12:36 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:12:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:12:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'jsshell-linux-x86_64.zip'], 16:12:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:12:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:12:36 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:12:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:12:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:12:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:36 INFO - https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:12:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:12:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:12:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:12:37 INFO - Downloaded 23882818 bytes. 16:12:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:12:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:12:38 INFO - Return code: 0 16:12:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:12: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')]} 16:12:38 INFO - https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:12:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:12:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:12:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:12:39 INFO - Downloaded 23882818 bytes. 16:12:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:12:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:12:41 INFO - Return code: 0 16:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:41 INFO - https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:12:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:12:43 INFO - Downloaded 10840152 bytes. 16:12:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:12:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:12:43 INFO - Return code: 0 16:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:43 INFO - https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:12:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:12:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:12:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:12:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:12:46 INFO - Downloaded 58391729 bytes. 16:12:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:12:46 INFO - mkdir: /builds/slave/test/properties 16:12:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:12:46 INFO - Writing to file /builds/slave/test/properties/build_url 16:12:46 INFO - Contents: 16:12:46 INFO - build_url:https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:12:46 INFO - Running post-action listener: _resource_record_post_action 16:12:46 INFO - Running post-action listener: set_extra_try_arguments 16:12:46 INFO - ##### 16:12:46 INFO - ##### Running populate-webroot step. 16:12:46 INFO - ##### 16:12:46 INFO - Running pre-action listener: _resource_record_pre_action 16:12:46 INFO - Running main action method: populate_webroot 16:12:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:46 INFO - '--tppagecycles', 16:12:46 INFO - '7'], 16:12:46 INFO - 'tests': ['tsvgm']}}, 16:12:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:12:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:12:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:12:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['damp', 'tps']}, 16:12:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'tests': ['damp', 'tps']}, 16:12:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['dromaeo_dom']}, 16:12:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['a11yr', 16:12:46 INFO - 'ts_paint', 16:12:46 INFO - 'tpaint', 16:12:46 INFO - 'sessionrestore', 16:12:46 INFO - 'sessionrestore_no_auto_restore', 16:12:46 INFO - 'tabpaint']}, 16:12:46 INFO - 'other-e10s': {'tests': ['a11yr', 16:12:46 INFO - 'ts_paint', 16:12:46 INFO - 'tpaint', 16:12:46 INFO - 'sessionrestore', 16:12:46 INFO - 'sessionrestore_no_auto_restore', 16:12:46 INFO - 'tabpaint']}, 16:12:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:12:46 INFO - 'ts_paint', 16:12:46 INFO - 'tpaint', 16:12:46 INFO - 'sessionrestore', 16:12:46 INFO - 'sessionrestore_no_auto_restore', 16:12:46 INFO - 'tabpaint']}, 16:12:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:12:46 INFO - 'ts_paint', 16:12:46 INFO - 'tpaint', 16:12:46 INFO - 'sessionrestore', 16:12:46 INFO - 'sessionrestore_no_auto_restore', 16:12:46 INFO - 'tabpaint']}, 16:12:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['a11yr', 16:12:46 INFO - 'ts_paint', 16:12:46 INFO - 'tpaint', 16:12:46 INFO - 'sessionrestore', 16:12:46 INFO - 'sessionrestore_no_auto_restore', 16:12:46 INFO - 'tabpaint']}, 16:12:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['a11yr', 16:12:46 INFO - 'ts_paint', 16:12:46 INFO - 'tpaint', 16:12:46 INFO - 'sessionrestore', 16:12:46 INFO - 'sessionrestore_no_auto_restore', 16:12:46 INFO - 'tabpaint']}, 16:12:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['tsvgx', 16:12:46 INFO - 'tsvgr_opacity', 16:12:46 INFO - 'tart', 16:12:46 INFO - 'tscrollx', 16:12:46 INFO - 'cart']}, 16:12:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:12:46 INFO - 'tsvgr_opacity', 16:12:46 INFO - 'tart', 16:12:46 INFO - 'tscrollx', 16:12:46 INFO - 'cart']}, 16:12:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'talos_options': ['--disable-e10s'], 16:12:46 INFO - 'tests': ['tp5o']}, 16:12:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'tests': ['tp5o']}, 16:12:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'talos_options': ['--disable-e10s', 16:12:46 INFO - '--xperf_path', 16:12:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:46 INFO - 'tests': ['tp5n']}, 16:12:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:46 INFO - 'talos_options': ['--xperf_path', 16:12:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:46 INFO - 'tests': ['tp5n']}}, 16:12:46 INFO - 'talos.zip': {'path': '', 16:12:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:46 INFO - Running post-action listener: _resource_record_post_action 16:12:46 INFO - ##### 16:12:46 INFO - ##### Running create-virtualenv step. 16:12:46 INFO - ##### 16:12:46 INFO - Running pre-action listener: _resource_record_pre_action 16:12:46 INFO - Running main action method: create_virtualenv 16:12:46 INFO - Creating virtualenv /builds/slave/test/build/venv 16:12:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:12:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:12:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:46 INFO - Using real prefix '/usr' 16:12:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:12:47 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:49 INFO - Installing pip.................done. 16:12:49 INFO - Return code: 0 16:12:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:49 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 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:12:49 INFO - Using env: {'DISPLAY': ':0', 16:12:49 INFO - 'HOME': '/home/cltbld', 16:12:49 INFO - 'LANG': 'en_US.UTF-8', 16:12:49 INFO - 'LANGUAGE': 'en_US:en', 16:12:49 INFO - 'LOGNAME': 'cltbld', 16:12:49 INFO - 'MAIL': '/var/mail/cltbld', 16:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:49 INFO - 'MOZ_NO_REMOTE': '1', 16:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:49 INFO - 'NO_EM_RESTART': '1', 16:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:49 INFO - 'PWD': '/builds/slave/test', 16:12:49 INFO - 'SHELL': '/bin/bash', 16:12:49 INFO - 'SHLVL': '1', 16:12:49 INFO - 'TERM': 'linux', 16:12:49 INFO - 'TMOUT': '86400', 16:12:49 INFO - 'USER': 'cltbld', 16:12:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:49 INFO - '_': '/tools/buildbot/bin/python'} 16:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:12:49 INFO - Cleaning up... 16:12:49 INFO - Return code: 0 16:12:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:49 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 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:49 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 16:12:49 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 16:12:49 INFO - Using env: {'DISPLAY': ':0', 16:12:49 INFO - 'HOME': '/home/cltbld', 16:12:49 INFO - 'LANG': 'en_US.UTF-8', 16:12:49 INFO - 'LANGUAGE': 'en_US:en', 16:12:49 INFO - 'LOGNAME': 'cltbld', 16:12:49 INFO - 'MAIL': '/var/mail/cltbld', 16:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:49 INFO - 'MOZ_NO_REMOTE': '1', 16:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:49 INFO - 'NO_EM_RESTART': '1', 16:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:49 INFO - 'PWD': '/builds/slave/test', 16:12:49 INFO - 'SHELL': '/bin/bash', 16:12:49 INFO - 'SHLVL': '1', 16:12:49 INFO - 'TERM': 'linux', 16:12:49 INFO - 'TMOUT': '86400', 16:12:49 INFO - 'USER': 'cltbld', 16:12:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:49 INFO - '_': '/tools/buildbot/bin/python'} 16:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:49 INFO - Downloading/unpacking psutil>=0.7.1 16:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:51 INFO - Installing collected packages: psutil 16:12:51 INFO - Running setup.py install for psutil 16:12:51 INFO - building 'psutil._psutil_linux' extension 16:12:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:12:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:12:52 INFO - building 'psutil._psutil_posix' extension 16:12:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:12:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:12:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:52 INFO - Successfully installed psutil 16:12:52 INFO - Cleaning up... 16:12:52 INFO - Return code: 0 16:12:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:12:52 INFO - Using env: {'DISPLAY': ':0', 16:12:52 INFO - 'HOME': '/home/cltbld', 16:12:52 INFO - 'LANG': 'en_US.UTF-8', 16:12:52 INFO - 'LANGUAGE': 'en_US:en', 16:12:52 INFO - 'LOGNAME': 'cltbld', 16:12:52 INFO - 'MAIL': '/var/mail/cltbld', 16:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:52 INFO - 'MOZ_NO_REMOTE': '1', 16:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:52 INFO - 'NO_EM_RESTART': '1', 16:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:52 INFO - 'PWD': '/builds/slave/test', 16:12:52 INFO - 'SHELL': '/bin/bash', 16:12:52 INFO - 'SHLVL': '1', 16:12:52 INFO - 'TERM': 'linux', 16:12:52 INFO - 'TMOUT': '86400', 16:12:52 INFO - 'USER': 'cltbld', 16:12:52 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:52 INFO - '_': '/tools/buildbot/bin/python'} 16:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:12:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:12:54 INFO - Installing collected packages: mozsystemmonitor 16:12:54 INFO - Running setup.py install for mozsystemmonitor 16:12:54 INFO - Successfully installed mozsystemmonitor 16:12:54 INFO - Cleaning up... 16:12:54 INFO - Return code: 0 16:12:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:54 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 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:12:54 INFO - Using env: {'DISPLAY': ':0', 16:12:54 INFO - 'HOME': '/home/cltbld', 16:12:54 INFO - 'LANG': 'en_US.UTF-8', 16:12:54 INFO - 'LANGUAGE': 'en_US:en', 16:12:54 INFO - 'LOGNAME': 'cltbld', 16:12:54 INFO - 'MAIL': '/var/mail/cltbld', 16:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:54 INFO - 'MOZ_NO_REMOTE': '1', 16:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:54 INFO - 'NO_EM_RESTART': '1', 16:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:54 INFO - 'PWD': '/builds/slave/test', 16:12:54 INFO - 'SHELL': '/bin/bash', 16:12:54 INFO - 'SHLVL': '1', 16:12:54 INFO - 'TERM': 'linux', 16:12:54 INFO - 'TMOUT': '86400', 16:12:54 INFO - 'USER': 'cltbld', 16:12:54 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:54 INFO - '_': '/tools/buildbot/bin/python'} 16:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:55 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:56 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:12:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:57 INFO - Downloading docopt-0.6.1.tar.gz 16:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:57 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:57 INFO - Running setup.py install for blobuploader 16:12:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:12:57 INFO - Running setup.py install for requests 16:12:58 INFO - Running setup.py install for docopt 16:12:58 INFO - Successfully installed blobuploader requests docopt 16:12:58 INFO - Cleaning up... 16:12:58 INFO - Return code: 0 16:12:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:58 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 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:58 INFO - Using env: {'DISPLAY': ':0', 16:12:58 INFO - 'HOME': '/home/cltbld', 16:12:58 INFO - 'LANG': 'en_US.UTF-8', 16:12:58 INFO - 'LANGUAGE': 'en_US:en', 16:12:58 INFO - 'LOGNAME': 'cltbld', 16:12:58 INFO - 'MAIL': '/var/mail/cltbld', 16:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:58 INFO - 'MOZ_NO_REMOTE': '1', 16:12:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:58 INFO - 'NO_EM_RESTART': '1', 16:12:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:58 INFO - 'PWD': '/builds/slave/test', 16:12:58 INFO - 'SHELL': '/bin/bash', 16:12:58 INFO - 'SHLVL': '1', 16:12:58 INFO - 'TERM': 'linux', 16:12:58 INFO - 'TMOUT': '86400', 16:12:58 INFO - 'USER': 'cltbld', 16:12:58 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:58 INFO - '_': '/tools/buildbot/bin/python'} 16:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:58 INFO - Running setup.py (path:/tmp/pip-wwQN6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:58 INFO - Running setup.py (path:/tmp/pip-YyPOol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:58 INFO - Running setup.py (path:/tmp/pip-vrVz91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:58 INFO - Running setup.py (path:/tmp/pip-91kIWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:58 INFO - Running setup.py (path:/tmp/pip-IWPl29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:58 INFO - Running setup.py (path:/tmp/pip-mRIyCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:58 INFO - Running setup.py (path:/tmp/pip-7qJR2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:59 INFO - Running setup.py (path:/tmp/pip-plRD7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:59 INFO - Running setup.py (path:/tmp/pip-BmF50U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:59 INFO - Running setup.py (path:/tmp/pip-B5l4KN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:59 INFO - Running setup.py (path:/tmp/pip-3JURay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:59 INFO - Running setup.py (path:/tmp/pip-Xl0nHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:59 INFO - Running setup.py (path:/tmp/pip-rW6BkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:59 INFO - Running setup.py (path:/tmp/pip-hjIfGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:59 INFO - Running setup.py (path:/tmp/pip-vUPuVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:59 INFO - Running setup.py (path:/tmp/pip-TCq4dH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:59 INFO - Running setup.py (path:/tmp/pip-fNywMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:59 INFO - Running setup.py install for manifestparser 16:12:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:12:59 INFO - Running setup.py install for mozcrash 16:12:59 INFO - Running setup.py install for mozdebug 16:13:00 INFO - Running setup.py install for mozdevice 16:13:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Running setup.py install for mozfile 16:13:00 INFO - Running setup.py install for mozhttpd 16:13:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Running setup.py install for mozinfo 16:13:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Running setup.py install for mozInstall 16:13:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Running setup.py install for mozleak 16:13:00 INFO - Running setup.py install for mozlog 16:13:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Running setup.py install for moznetwork 16:13:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Running setup.py install for mozprocess 16:13:01 INFO - Running setup.py install for mozprofile 16:13:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:13:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:13:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:13:01 INFO - Running setup.py install for mozrunner 16:13:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:13:01 INFO - Running setup.py install for mozscreenshot 16:13:01 INFO - Running setup.py install for moztest 16:13:01 INFO - Running setup.py install for mozversion 16:13:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:13:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:13:01 INFO - Cleaning up... 16:13:01 INFO - Return code: 0 16:13:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:01 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 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:01 INFO - Using env: {'DISPLAY': ':0', 16:13:01 INFO - 'HOME': '/home/cltbld', 16:13:01 INFO - 'LANG': 'en_US.UTF-8', 16:13:01 INFO - 'LANGUAGE': 'en_US:en', 16:13:01 INFO - 'LOGNAME': 'cltbld', 16:13:01 INFO - 'MAIL': '/var/mail/cltbld', 16:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:01 INFO - 'MOZ_NO_REMOTE': '1', 16:13:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:01 INFO - 'NO_EM_RESTART': '1', 16:13:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:01 INFO - 'PWD': '/builds/slave/test', 16:13:01 INFO - 'SHELL': '/bin/bash', 16:13:01 INFO - 'SHLVL': '1', 16:13:01 INFO - 'TERM': 'linux', 16:13:01 INFO - 'TMOUT': '86400', 16:13:01 INFO - 'USER': 'cltbld', 16:13:01 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:01 INFO - '_': '/tools/buildbot/bin/python'} 16:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:01 INFO - Running setup.py (path:/tmp/pip-6NKNSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:01 INFO - Running setup.py (path:/tmp/pip-CV9Q3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:01 INFO - Running setup.py (path:/tmp/pip-VUoCn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:02 INFO - Running setup.py (path:/tmp/pip-JOGIg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:02 INFO - Running setup.py (path:/tmp/pip-bV2SJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:02 INFO - Running setup.py (path:/tmp/pip-4szhG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:02 INFO - Running setup.py (path:/tmp/pip-0195im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:02 INFO - Running setup.py (path:/tmp/pip-ae_wKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:02 INFO - Running setup.py (path:/tmp/pip-ObFZdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:02 INFO - Running setup.py (path:/tmp/pip-m_gJoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:02 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)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:02 INFO - Running setup.py (path:/tmp/pip-IgP03d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:02 INFO - Running setup.py (path:/tmp/pip-gA9ZOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:02 INFO - Running setup.py (path:/tmp/pip-CVJGur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:02 INFO - Running setup.py (path:/tmp/pip-eUjz4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:02 INFO - Running setup.py (path:/tmp/pip-AUzLlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:02 INFO - Running setup.py (path:/tmp/pip-AqpuFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:02 INFO - Running setup.py (path:/tmp/pip-CHgaIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:04 INFO - Downloading blessings-1.6.tar.gz 16:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:13:05 INFO - Installing collected packages: blessings 16:13:05 INFO - Running setup.py install for blessings 16:13:05 INFO - Successfully installed blessings 16:13:05 INFO - Cleaning up... 16:13:05 INFO - Return code: 0 16:13:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:05 INFO - Reading from file tmpfile_stdout 16:13:05 INFO - Current package versions: 16:13:05 INFO - argparse == 1.2.1 16:13:05 INFO - blessings == 1.6 16:13:05 INFO - blobuploader == 1.2.4 16:13:05 INFO - docopt == 0.6.1 16:13:05 INFO - manifestparser == 1.1 16:13:05 INFO - mozInstall == 1.12 16:13:05 INFO - mozcrash == 0.17 16:13:05 INFO - mozdebug == 0.1 16:13:05 INFO - mozdevice == 0.48 16:13:05 INFO - mozfile == 1.2 16:13:05 INFO - mozhttpd == 0.7 16:13:05 INFO - mozinfo == 0.9 16:13:05 INFO - mozleak == 0.1 16:13:05 INFO - mozlog == 3.1 16:13:05 INFO - moznetwork == 0.27 16:13:05 INFO - mozprocess == 0.22 16:13:05 INFO - mozprofile == 0.28 16:13:05 INFO - mozrunner == 6.11 16:13:05 INFO - mozscreenshot == 0.1 16:13:05 INFO - mozsystemmonitor == 0.0 16:13:05 INFO - moztest == 0.7 16:13:05 INFO - mozversion == 1.4 16:13:05 INFO - psutil == 3.1.1 16:13:05 INFO - requests == 1.2.3 16:13:05 INFO - wsgiref == 0.1.2 16:13:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:05 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 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:05 INFO - Using env: {'DISPLAY': ':0', 16:13:05 INFO - 'HOME': '/home/cltbld', 16:13:05 INFO - 'LANG': 'en_US.UTF-8', 16:13:05 INFO - 'LANGUAGE': 'en_US:en', 16:13:05 INFO - 'LOGNAME': 'cltbld', 16:13:05 INFO - 'MAIL': '/var/mail/cltbld', 16:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:05 INFO - 'MOZ_NO_REMOTE': '1', 16:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:05 INFO - 'NO_EM_RESTART': '1', 16:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:05 INFO - 'PWD': '/builds/slave/test', 16:13:05 INFO - 'SHELL': '/bin/bash', 16:13:05 INFO - 'SHLVL': '1', 16:13:05 INFO - 'TERM': 'linux', 16:13:05 INFO - 'TMOUT': '86400', 16:13:05 INFO - 'USER': 'cltbld', 16:13:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:05 INFO - '_': '/tools/buildbot/bin/python'} 16:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:13:05 INFO - Cleaning up... 16:13:05 INFO - Return code: 0 16:13:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:13:05 INFO - Using env: {'DISPLAY': ':0', 16:13:05 INFO - 'HOME': '/home/cltbld', 16:13:05 INFO - 'LANG': 'en_US.UTF-8', 16:13:05 INFO - 'LANGUAGE': 'en_US:en', 16:13:05 INFO - 'LOGNAME': 'cltbld', 16:13:05 INFO - 'MAIL': '/var/mail/cltbld', 16:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:05 INFO - 'MOZ_NO_REMOTE': '1', 16:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:05 INFO - 'NO_EM_RESTART': '1', 16:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:05 INFO - 'PWD': '/builds/slave/test', 16:13:05 INFO - 'SHELL': '/bin/bash', 16:13:05 INFO - 'SHLVL': '1', 16:13:05 INFO - 'TERM': 'linux', 16:13:05 INFO - 'TMOUT': '86400', 16:13:05 INFO - 'USER': 'cltbld', 16:13:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:05 INFO - '_': '/tools/buildbot/bin/python'} 16:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:05 INFO - Downloading/unpacking jsonschema 16:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:13:07 INFO - Downloading/unpacking functools32 (from jsonschema) 16:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:07 INFO - Downloading functools32-3.2.3-2.tar.gz 16:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:13:07 INFO - warning: no files found matching '*.txt' 16:13:07 INFO - no previously-included directories found matching 'build' 16:13:07 INFO - no previously-included directories found matching 'dist' 16:13:07 INFO - no previously-included directories found matching '.git*' 16:13:07 INFO - Installing collected packages: jsonschema, functools32 16:13:07 INFO - Running setup.py install for functools32 16:13:08 INFO - warning: no files found matching '*.txt' 16:13:08 INFO - no previously-included directories found matching 'build' 16:13:08 INFO - no previously-included directories found matching 'dist' 16:13:08 INFO - no previously-included directories found matching '.git*' 16:13:08 INFO - Successfully installed jsonschema functools32 16:13:08 INFO - Cleaning up... 16:13:08 INFO - Return code: 0 16:13:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:13: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')]} 16:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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')]} 16:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4e991bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:13: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', 'flake8'] in /builds/slave/test/build 16:13: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 flake8 16:13:08 INFO - Using env: {'DISPLAY': ':0', 16:13:08 INFO - 'HOME': '/home/cltbld', 16:13:08 INFO - 'LANG': 'en_US.UTF-8', 16:13:08 INFO - 'LANGUAGE': 'en_US:en', 16:13:08 INFO - 'LOGNAME': 'cltbld', 16:13:08 INFO - 'MAIL': '/var/mail/cltbld', 16:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:08 INFO - 'MOZ_NO_REMOTE': '1', 16:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:08 INFO - 'NO_EM_RESTART': '1', 16:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:08 INFO - 'PWD': '/builds/slave/test', 16:13:08 INFO - 'SHELL': '/bin/bash', 16:13:08 INFO - 'SHLVL': '1', 16:13:08 INFO - 'TERM': 'linux', 16:13:08 INFO - 'TMOUT': '86400', 16:13:08 INFO - 'USER': 'cltbld', 16:13:08 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:08 INFO - '_': '/tools/buildbot/bin/python'} 16:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:08 INFO - Downloading/unpacking flake8 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:13:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:13:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:13:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:13:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:13:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:13:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:13:10 INFO - no previously-included directories found matching 'docs/_build' 16:13:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:13:11 INFO - Running setup.py install for pep8 16:13:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:13:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:13:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:13:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:13:11 INFO - no previously-included directories found matching 'docs/_build' 16:13:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:13:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:13:11 INFO - Cleaning up... 16:13:11 INFO - Return code: 0 16:13:11 INFO - Running post-action listener: _resource_record_post_action 16:13:11 INFO - Running post-action listener: _start_resource_monitoring 16:13:11 INFO - Starting resource monitoring. 16:13:11 INFO - ##### 16:13:11 INFO - ##### Running install step. 16:13:11 INFO - ##### 16:13:11 INFO - Running pre-action listener: _resource_record_pre_action 16:13:11 INFO - Running main action method: install 16:13:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:11 INFO - Reading from file tmpfile_stdout 16:13:11 INFO - Detecting whether we're running mozinstall >=1.0... 16:13:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:13:11 INFO - Reading from file tmpfile_stdout 16:13:11 INFO - Output received: 16:13:11 INFO - Usage: mozinstall [options] installer 16:13:11 INFO - Options: 16:13:11 INFO - -h, --help show this help message and exit 16:13:11 INFO - -d DEST, --destination=DEST 16:13:11 INFO - Directory to install application into. [default: 16:13:11 INFO - "/builds/slave/test"] 16:13:11 INFO - --app=APP Application being installed. [default: firefox] 16:13:11 INFO - mkdir: /builds/slave/test/build/application 16:13:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:13:28 INFO - Reading from file tmpfile_stdout 16:13:28 INFO - Output received: 16:13:28 INFO - /builds/slave/test/build/application/firefox/firefox 16:13:28 INFO - Running post-action listener: _resource_record_post_action 16:13:28 INFO - ##### 16:13:28 INFO - ##### Running run-tests step. 16:13:28 INFO - ##### 16:13:28 INFO - Running pre-action listener: _resource_record_pre_action 16:13:28 INFO - Running main action method: run_tests 16:13:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:13:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:13:28 INFO - Python 2.7.3 16:13:28 INFO - Return code: 0 16:13:28 INFO - grabbing minidump binary from tooltool 16:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:28 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 0x1547410>, '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 0x1548310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15487a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:13:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:13:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:13:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:13:28 INFO - Return code: 0 16:13:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:13:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:13:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:13:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:13:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:13:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:13:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:13:29 INFO - Return code: 0 16:13:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 16:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 16:13:29 INFO - Using env: {'DISPLAY': ':0', 16:13:29 INFO - 'HOME': '/home/cltbld', 16:13:29 INFO - 'LANG': 'en_US.UTF-8', 16:13:29 INFO - 'LANGUAGE': 'en_US:en', 16:13:29 INFO - 'LOGNAME': 'cltbld', 16:13:29 INFO - 'MAIL': '/var/mail/cltbld', 16:13:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:13:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:29 INFO - 'MOZ_NO_REMOTE': '1', 16:13:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:29 INFO - 'NO_EM_RESTART': '1', 16:13:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:29 INFO - 'PWD': '/builds/slave/test', 16:13:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:13:29 INFO - 'SHELL': '/bin/bash', 16:13:29 INFO - 'SHLVL': '1', 16:13:29 INFO - 'TERM': 'linux', 16:13:29 INFO - 'TMOUT': '86400', 16:13:29 INFO - 'USER': 'cltbld', 16:13:29 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420', 16:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:29 INFO - '_': '/tools/buildbot/bin/python'} 16:13:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 16:13:29 INFO - mozversion application_buildid: 20160505133015 16:13:29 INFO - mozversion application_changeset: e5a10bc7dac4ee2453d8319165c1f6578203eac7 16:13:29 INFO - mozversion application_display_name: Nightly 16:13:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:13:29 INFO - mozversion application_name: Firefox 16:13:29 INFO - mozversion application_remotingname: firefox 16:13:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:13:29 INFO - mozversion application_vendor: Mozilla 16:13:29 INFO - mozversion application_version: 49.0a1 16:13:29 INFO - mozversion platform_buildid: 20160505133015 16:13:29 INFO - mozversion platform_changeset: e5a10bc7dac4ee2453d8319165c1f6578203eac7 16:13:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:13:29 INFO - mozversion platform_version: 49.0a1 16:13:29 INFO - using testdate: 1462490009 16:13:29 INFO - actual date: 1462490009 16:13:29 INFO - starting webserver on 'localhost:38154' 16:13:29 INFO - SUITE-START | Running 2 tests 16:13:29 INFO - TEST-START | dromaeo_css 16:13:29 INFO - operating with platform_type : linux_ 16:13:29 INFO - Initialising browser for dromaeo_css test... 16:13:29 INFO - TEST-INFO | started process 4042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxBqGG/profile http://localhost:38154/getInfo.html) 16:13:32 INFO - PROCESS | 4042 | 16:13:32 INFO - PROCESS | 4042 | (process:4110): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:13:33 INFO - PROCESS | 4042 | __metrics Screen width/height:1600/1200 16:13:33 INFO - PROCESS | 4042 | colorDepth:24 16:13:33 INFO - PROCESS | 4042 | Browser inner width/height: 1024/697 16:13:33 INFO - PROCESS | 4042 | __metrics 16:13:33 ERROR - PROCESS | 4042 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 16:13:34 INFO - TEST-INFO | 4042: exit 0 16:13:34 INFO - Browser initialized. 16:13:34 INFO - Running cycle 1/1 for dromaeo_css test... 16:13:34 INFO - TEST-INFO | started process 4161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxBqGG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:36 INFO - PROCESS | 4161 | 16:13:36 INFO - PROCESS | 4161 | (process:4221): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:13:46 INFO - PROCESS | 4161 | RSS: Main: 162893824 16:13:46 INFO - PROCESS | 4161 | 16:14:36 INFO - PROCESS | 4161 | 1462490076109 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:16:43 INFO - PROCESS | 4161 | Cycle 1(1): loaded http://localhost:38154/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38154/tests/dromaeo/cssquery-ext.html) 16:16:44 INFO - PROCESS | 4161 | RSS: Main: 166907904 16:16:44 INFO - PROCESS | 4161 | 16:19:46 INFO - PROCESS | 4161 | Cycle 1(1): loaded http://localhost:38154/tests/dromaeo/cssquery-ext.html (next: http://localhost:38154/tests/dromaeo/cssquery-jquery.html) 16:19:47 INFO - PROCESS | 4161 | RSS: Main: 166547456 16:19:47 INFO - PROCESS | 4161 | 16:22:49 INFO - PROCESS | 4161 | Cycle 1(1): loaded http://localhost:38154/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38154/tests/dromaeo/cssquery-mootools.html) 16:22:50 INFO - PROCESS | 4161 | RSS: Main: 167649280 16:22:50 INFO - PROCESS | 4161 | 16:25:51 INFO - PROCESS | 4161 | Cycle 1(1): loaded http://localhost:38154/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38154/tests/dromaeo/cssquery-prototype.html) 16:25:52 INFO - PROCESS | 4161 | RSS: Main: 165474304 16:25:52 INFO - PROCESS | 4161 | 16:28:54 INFO - PROCESS | 4161 | Cycle 1(1): loaded http://localhost:38154/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38154/tests/dromaeo/cssquery-yui.html) 16:28:55 INFO - PROCESS | 4161 | RSS: Main: 166899712 16:28:55 INFO - PROCESS | 4161 | 16:31:57 INFO - PROCESS | 4161 | Cycle 1(1): loaded http://localhost:38154/tests/dromaeo/cssquery-yui.html (next: http://localhost:38154/tests/dromaeo/cssquery-dojo.html) 16:31:58 INFO - PROCESS | 4161 | RSS: Main: 168718336 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | __start_tp_report 16:31:58 INFO - PROCESS | 4161 | _x_x_mozilla_page_load 16:31:58 INFO - PROCESS | 4161 | _x_x_mozilla_page_load_details 16:31:58 INFO - PROCESS | 4161 | |i|pagename|runs| 16:31:58 INFO - PROCESS | 4161 | |0;dojo.html;3168.6831316868315;3187.2828613561965;3207.9518807217896;3234.3207926335467;3236.093893709761;5406.756695948687;5417.45825417458;5455.427180146082;5500.862478438041;5567.805126820562;5411.215373770801;5412.512873841353;5476.58925580581;5487.341519017724;5495.120780675093;4882.853514394556;4963.305137280783;5084.516970887778;5123.359580052494;5180;673.5844734392284;4888.877778055541;5014.974925125369;5096.668716533413;5138.434772175057;4997.775100120485;5047.066292735855;5072.644914855961;5073.188289873621;5074.188129899217;5113.130767769489;5115.386153661547;5120.692758434506;5125.666831655931;5149;4914.11545921734;4969.378827646544;5004.074246264453;5014.2979982802435;5063.493650634944;5386.73066346685;5480.835574932758;5503.11950087988;5505.724713764296;5541.722913854332;5706.600537962344;5958.314793798725;5963.761449542013;5970.948125901355;5990.670513121777;5748.655080695172;5763.711814409305;5767.509761670263;5872.442117998783;5879.176915231871;5445.727713614343;5510.9448905510835;5522.723863806834;5522.834314970557;5525.4474552544825;5848.619839710405;5943.613665111754;5986.551008674367;6090.969545152246;6098.969505152535;5799.2750906136735;5886.411358864165;5886.558508111909;5891.587588868823;5935.940640593539;5941.970290148522;5955.225820643289;5958.433948774859;5975.850603734854;5984.640921544721;5661.915071273934;5741.655500669973;5756.5682573807135;5928.140419639129;5952.821415357546;5774.682392468455;5860.765569377262;5861.296644402699;5868.618539794985;5899.115132730039;4328.761918094472;4335.046289816235;4346.261135606955;4350.543192964757;4368.934465983013;3927.8625248116177;3942.2312649033165;3975.940360894589;3990.4812374391145;4016.879493615196;5475.6440831345835;5479.205515200274;5499.780008799603;5533.059379905426;5571.164325351148;5839.970800145972;5859.882802343929;5860.941390586039;5873.442023007808;5877.735501902424;5467.398586155519;5580.525655319262;5589.804356847572;5669.036263835075;5670.121131224584;4888.902221955541;4919.606431485473;4925.975370123127;4935.90128197434;4970.030843985388;6166.845828854314;6167.198264225622;6174.289956655042;6179.907301390483;6188.969055154786;5798.33319168301;5823.126531020381;5837.153612726216;5893.027650437802;5926.970365148147;6087.756489740361;6095.8780824382375;6097.75608975636;6101.60339577926;6123.4488895999575;5675.545956323402;5789.421057894177;5819.359870414336;5843.386444423274;5852.326982397079;5841.678707671119;5918.082697181943;5941.970290148522;5956.285245770535;5968.045112781934;5710.057840456444;5756.366799651951;5760.395158508296;5777.508911742549;5800.24596802413;5634.6337488063145;5749.8275051748515;5761.020626493422;5787.305523337226;5790.044642634087;5665.688387138747;5715.085586306171;5730.598858079894;5749.511291540266;5786.3056433228285;5798.33319168301;5838.386969368155;5859.560532959959;5887.087501437284;5889.852753681193;5535.11438169907;5574.749136288796;5599.132134519156;5744.712764361849;5745.971270143623 16:31:58 INFO - PROCESS | 4161 | |1;ext.html;582.2372691773777;616.2235583165212;641.9325970773069;648.1185587600864;681.1621705302475;36482.270354592925;40820.59179408205;41039.79480102595;41206.38190427146;41324.38013429797;40712;41460.79269603648;41530.7923460383;41716.37425438621;41727.79136104315;8817.030126686075;8838;8865.822683546325;8889.822203555925;8890.911090889073;8747.606357713918;8816.382853200279;8833.11668833118;8878.467291962503;8886.86669699955;8833.95583022084;8876.911230887674;8903.77740556488;8908;8941.687040953568;8826.779330516723;8831.73504794854;8915.955420222923;8923.241524470428;8938.508382038985;8736.60685269158;8792.956035219782;8806.86789698155;8808.458519759017;8814.25078868297;34224.31551368959;39415.211695765924;39562.20875582501;39623.603763962565;39653.40519892204;3486.877959271413;3531.329047480996;3788.052986753312;4092.6930480214105;4245.448091748084;30505.69494305051;30559;30699.846500767577;30848.691513085032;32027.51958720621;4271.829126834954;4317.438732964694;4390.670699697504;4472.105578884204;4513.7066090704;255327;258675;260757.69621151962;261631.91840407904;262695;32398.514022289684;33114.834425827714;33265.667343326255;34557.82721086378;34987;36029;36420.08949776277;36435.27129457396;36441;36512;15022.474213402478;15202.695946081016;15208.847911520743;15256.313465894062;15327.463538776312;12093.153479256544;12114.81827772584;12187.146899717112;12204.328761918003;12209.877901221053;8614.44006139599;8626;8656.61045252965;8679.349048821237;8721.258693011165;3358.983205083959;3389.084947064279;3400.7417255615583;3412.2322477442376;3466.4626982817704;1091.307020042263;1094.9014588687057;1122.4612186150694;1224.302147775777;1235.123062625528;31515.36969260602;32354.35291294208;32370.35259294801;32597.837010814794;32702.345953080807;1132.6885106595587;1159.6984783956232;1182.4501606752729;1201.9459124339426;1247.7904747678406;16150.35398584067;16198.919005405134;16263;16433.753493697604;16497.257623406975;450514;452785;452917;458978;460879;55017;55421.445785541626;55584.16623750647;55687.72156139275;55707.442925571035;1427.8505802828822;1446.7902154187793;1453.7673972164403;1462.714770619719;1484.5249520153448;30238.84880575583;30270.54594181045;30433.23916902095;30469.390612188075;30669;54915.45084549103;57450.712746437675;57725.71137144455;62756.117316478754;62802;400655;401932;405570;405871;405933;43525.78237108921;43813.34279985739;43823.780881095394;44259;44416.777916110215;48250.517494824606;48477.272840906706;48756.26865596948;49558.752206238736;49618.751906240235;41257.58742412538;41872.1625567493;42017.36973945332;42797.35803962881;43392.56607433885;41628.79185604052;41794.79102604589;41813.00591628139;41847;42441.575584243765;41897.79051104847;42131.789341053096;42208.15583688248;42503.5749642512;43024;36359.45460818037;36802;37075.8146209278;37425.4386184202;38089.6191038086;28711.569326459703;28987.855060725404;29119;29230;29360 16:31:58 INFO - PROCESS | 4161 | |2;jquery.html;394.8835093647374;399.357035173371;400.3394399241251;400.95990400959926;425.9236223410872;13810;17817.910910445447;18659.626807463843;19395.612087758236;19517.902410487965;18525.2589896404;18662.906685466587;18932.148053337598;19720.901395493;19804.207831686752;5647.802326918559;5722.7424765885535;5902.173695682608;5903.114532820068;5906.704664766766;5541.168824676311;5794.130880367958;5799.942000580004;5845.298564172305;5848.619839710405;5891.322497912752;5898.852528686774;5903.557233207505;5907.468327850493;5920.904632642953;5421.159720243369;5730.713464326767;5751.25233719618;5892.027815410452;5896.056630939051;5728.541716662657;5734.713264336809;5752.913706294409;5755.338136114358;5771.884562308773;17610.647787044316;21863.562728745495;22604.43488912774;22816.31551053471;22830.200942967083;2375.3111597636666;2444.8899799509063;2452.301094188148;2491.2152671908293;3162.6521082681115;14330.856691433057;14917;15303.540893773205;15935.521934341989;16275.128360238607;2073.5749171419825;2115.6932244824416;2139.0801955159277;2158.5143342747915;2199.9010044548036;90366;91795.54102229513;93327;94183;95787.52106239424;16799.748003779954;17385.65228695419;17625.383111591033;17743.290268389377;17883.642327153386;30424.54363184554;30492.237694057825;30791.23021924425;31079.68920310768;31133.68866311353;9485.762855928657;9995.050470205319;10039.799204015879;10045.497725113715;10049.447280399501;8292.087870334259;8340.916590834135;8345.874811877828;8366.916330836613;8483.915160848435;8832.470051796912;8927.10728927115;8963;8985.101489850962;9004.36969412148;3475.8957231283102;3584.605693373727;3586.6771990520456;3601.7658852174527;3611.7652352596997;959.9952000239954;986.5609803637456;1030.1038096855784;1091.5579190427732;1100.9394483303495;18906.62186756257;19210.231590736494;19365.322213722735;19667.508312292022;19871.801281986995;1048.5753269925692;1054.4569546683465;1084.7505073832904;1086.7065892209218;1106.756513567014;10647.84028239577;11002.229843911009;11035.668929932115;11036.779264414827;11081.833772493419;406874;415876;422887;422917;423459;87934;92609;92670;93190.53404732933;93885.06114938763;1053.3206082077018;1072.3780207479488;1075.1989767623015;1096.660035389032;1104.5581767293147;17134.485965421056;17201.311947521957;17289.56776080608;17325.220365083853;17382.739258910875;36780;37509.624903751705;37645;38457.615423846524;38742.2251554973;49615.255771164186;51932.48067519276;54416;56327.15509267282;56673.433265668464;8878;9009.369344145847;9049.864252036094;9087.09129087086;9258.305627077862;9875.358101723365;13132.343382831012;13360.665983350495;13491.527796527467;13599;10863.837042444527;11428.200025998432;11460.338250953731;11665.24175928562;11869.40652967331;11458.885411145782;11485.885141148481;11487.138464615355;11747.706307342043;11777.057835373322;11726.355050471964;11769.234999724991;11837.704057398634;11856.288622682667;11862.644120676394;9001.819963600823;9012.86480702803;9069.36514444009;9093.909060909571;9135.223506002065;11308.49111789955;11651.53393864236;11669.066474682175;11793.41032948332;11858.229215100991 16:31:58 INFO - PROCESS | 4161 | |3;mootools.html;1560.656655535782;1566.248200863585;1764.7352897065439;1847.3072597775833;1852.8888266704007;7558.395328373731;8041.43709940303;8230.75307740768;8765.47407155571;8805.295576353892;8031.357491400689;8239.217274358942;8326.333893288538;8333.833323333529;8411.285040771541;2250.628646273363;2290.4159439342943;2290.541891621674;2291.32405940248;2296.196331284054;2239.070785623964;2258.22091378475;2286.199830059474;2287.0737351372723;2292.128990983424;2229.308914236584;2235.8099561537288;2237.8545394549383;2291.1522736587403;2291.9083236670513;2217.0355895185567;2225.766294539066;2252.7972482476657;2253.4141123307977;2253.9774602253933;2241.1595651630637;2269.489364892886;2275.4311422144465;2275.954480910389;2283.908643654252;8380.622871970776;8394.748157555283;8398.83202335956;8411.200935911078;8424.957875210646;4971.204607262821;5155.484451554852;5176.352955880515;5184.092783762826;5186.611004174702;3233.466478031122;3306.371789360038;3464.688178063961;3473.4789781532972;3503.2818272254126;1111.6887271563894;1117.5194666293492;1121.685927940178;1132.416805345248;1146.4611632532697;12060.517579296818;12089.335086570236;12094.213876098114;12103;12120.212186207957;4503.482099558527;4542.568455996675;4645.442546894395;4648.697834640738;4683;10008.949955250322;10012.299139060342;10013.699589012342;10087.243456740774;10093.646722364829;5202.531772140525;5264.184051472028;5311.203319502106;5336.4129945705945;5338.652987555796;4316.935245971313;4714.38712967312;4746;4820.807167713252;4935.481774413706;3789.962100379016;3884.6503814656817;3912.4131380293184;3916.6475017248013;3976.661983731415;1253.367049639926;1272.497363541396;1273.38877338876;1275.387813849339;1280.3982128399637;912.3020889019905;1002.9197664186847;1009.0313299232819;1029.4080903480528;1048.9003544663244;5819.505342045889;5863.472287494146;6123.540734444845;6125.785597504155;6143.109249158936;929.8884133903974;1028.1209565821264;1035.342557475993;1043.7077618266896;1068.7274744939991;4061.593840615915;4066.877993660195;4076.062505623723;4080.2859499587453;4126.824858308015;10600.25798194119;11018.283811552223;11163.162762792823;11164.274322169033;11174.161937854693;10937.289076210021;10964.342139471657;10988.340699558052;11025.944870275598;11089.667309980552;8236.176382361717;8310.875336869953;8361.498310101291;8363.874541881876;8410.285125764378;5791.333996590446;5877.059670452878;5910.556708246812;5914.4381283777975;6047.153398524298;9568.61725530971;10168;10212.744681383025;10236.129928955825;10247.538860751136;10005.149562287286;10193;10193.337433066827;10282.280240383101;10379.429131397847;6845.726170953106;7224.13310402755;7484;7692.499987500794;7759.806004849924;8453.915460845314;8455;8456.915430845613;8482.703105391281;8546.957265213634;7580.658870350958;7799.5320280783335;7810.687572497264;7883.960580197062;7960.721374751856;7616.8476630468185;7743.070831500256;7750.224977502205;7794.2985131338455;7836.529808211526;7100.005999160018;7148.28517148281;7176.425885929218;7176.856462870818;7187.281271872771;4887.975560122177;4980.526849949249;4985.326980857538;5057.898842023213;5079.314292570456;4779.187538118459;5038.672486288379;5062.746862656779;5104.3619547556555;5180.922286165786 16:31:58 INFO - PROCESS | 4161 | |4;prototype.html;338.59199664404395;340.90965894038084;344.4110570923721;346.1346633416459;348.77678285897014;5605.355384130826;5680.687562184078;5688.459596338341;5701.942980570193;5742.368339482654;5599.804006859761;5607.971960140204;5609.747561359738;5632.239647647566;5633.408492108331;4153.646940010103;4201.894952626193;4275.529691733922;4317.201317756209;4327.372530983005;4244.660427165834;4275.35869619558;4288.76411797351;4298.183345164424;4336.544662810406;4325.156594464081;4330.653547716191;4332.176886391576;4345.413369195166;4351.26028575143;4090.3455447128613;4261.87214383569;4274.119387469504;4307.440032795748;4344.543822898598;3697.4084146536693;3743.887683369503;3759.3984962405884;3761.0409345616968;3762.5484941806885;5353.705546194958;5361.919571206435;5372.301600791872;5414.43148469409;5430.67415955044;4597.60920321773;4863.465018847924;4868.6124454530645;4893.559579637849;4935.457099719028;5686.175504551817;5771.855703607402;5772.855678608026;5774.624649397818;5787.971060144672;4033.1127152026506;4065.3088974874045;4071.9389209161886;4089.243489954338;4099.364598487239;5907.556933229982;6001.549883758736;6003.909941350883;6027.914975304443;6104.389561043905;4159.8960025999595;4166.437530933345;4183.6234738873645;4186.748795072244;4198.06888831135;4220.556841531657;4245.936310955338;4247.702660813775;4247.745135291892;4250.744955302692;4674.984634910464;4867.829625963074;4869.245266983623;4877.317175595419;4892.339534162842;5831.79588714393;5849.678267695318;5860.619059761102;5910.320313163955;5912.142739302777;1436.4469679173594;1439.3450979804163;1448.7754398068105;1454.2655958740768;1456.5994351553409;921.3228277216241;922.7324076017879;933.5798890499302;1000.4697510319543;1010.8332125199407;5397.298351214396;5426.321709786277;5469.179623056494;5484.890302193934;5523.309586301712;5818.854528636733;5836.9124463133085;5848.561357898175;5851.707414629252;5857.20927674767;5241.475852414805;5372.838814835562;5379.821172425174;5430.728463576806;5547.5007249347755;3036.2409397650586;3066.3254084101463;3070.063630592646;3071.5239137933654;3074.400491904057;5707.771689132472;5766.596338256366;5909.87712334655;5917.911231331534;5929.0513517836935;5776.566757493205;5829.504492118217;5845.970770146122;5872.882542349214;5915.260592425947;5837.416258374214;5858.677772722541;5884.705764711747;5889.823305300848;5923.229980102646;5524.91712624319;5561.221428999862;5682.7782026864425;5722.971385143048;5796.42035796417;5655.858603534944;5683.545316374597;5685.271069886395;5712.200291959046;5733.197352370755;5836.628392327714;5925.933332000282;5974.731137098928;5982.52139828804;5990;4227.725197862129;4246.64107485614;4264.040590867155;4279.550647182001;4282.78586070689;6138.355472675305;6138.570300078952;6144.201253836973;6147.508199344131;6160.907586386118;5687.971560142173;5866.735996880066;5870.207521984648;5908.261467316585;5937.524998000236;5847.532197424281;5861.179434879035;5872.765089396547;5882.617629854046;5914.201582786269;5482.862928426821;5695.658260504383;5823.912641310299;5865.765369385177;5873.911891321548;5917.881642367042;5933.614315069679;5944.554158438221;5947.553933455095;5951.613145145551;5569.832905012856;5740.913886291793;5754.9424505754405;5806.912896306474;5810.360860305447 16:31:58 INFO - PROCESS | 4161 | |5;yui.html;735.4814855526854;868.3140319147874;875.1292463998315;883.2139395937612;888.9466632002083;13997.16017038977;14484.420623175074;14486.637834054154;14680.265986700675;14797.3341199646;13716.451341946311;14303.642408939755;14305.78541321881;14306.284685765697;14385.424583016693;702.6802804723839;819.2217393476207;823.1644880446352;844.1684940333766;844.8648216285395;844.679021971653;848.5248260973874;849.180540778149;851.1913682002091;852.5140669818206;899.9235065019449;905.0587389115312;905.1988989744058;905.8143080668447;906.374601524949;884.0938038510513;895.7939673875013;901.4501154295874;901.733988473402;902.6660135749748;645.0549944331577;653.9705713242915;656.7996760987896;657.4477438951257;659.0147729144911;13696.93151534246;13714.51999180024;13764.17414955096;13775.448982040707;13846.792298115537;13891.861081389157;13948.442062317496;13999.720005599831;14006.71986560263;14109.294535273195;11369.658910232707;11397.373144477051;11529.135314851335;11539.769204615945;11788.705282367924;3737.5141231639986;3814.7329686921926;3861.9613803861885;3893.0851249956236;4004.2191772604356;52138.21792673113;53002.469975300526;54603.18095228575;55038.724806376114;55190.17214741782;10790.298630588986;11190.720231994103;11279.041281491001;11305.943470282597;11396.316221026766;20412.183512659623;20453.48866278355;20490.897545512176;20554;20835;7556.168821429638;7628.427867909818;7745.728899488491;7800.648970796327;8074.152214017561;4487.910241795146;4653.255479123324;4668.15973124834;4696.95303046972;4837.782299796518;3245.075153581218;3305.058058453329;3317.336532693471;3323.8670453182085;3325.584301962255;561.1302481154195;605.4883623338272;607.9392060793886;658.5159907468008;699.3635791429774;634.9936500634934;753.2806170107534;754.4718696912183;779.1546172402893;791.7387262203459;8780.517071561126;9206.217471514863;9216.861747073799;9245.676401325922;9268.25853931684;665.2216906219735;768.4428789127837;774.1755030892082;777.4014009212874;782.3154739602847;4097.221527909747;4262.339337402707;4301.78491075445;4327.2643650579475;4365.214261432972;55621;55848;56023;61053;61481;54200;54313.72843135759;54330.72834635802;56017;57056;51347;51732.48267517277;52180.73909630428;52193.7390313046;52510.21234681483;8964.282857371525;9003.549822508718;9051;9189.77025574366;9237.769055773659;32818.67181328156;32939.17652058718;33285.66714332825;33791.66208337984;34077.82961085278;43344.133117336845;44602;44734.31594104364;45190.09619807651;45764.54235457603;12155.331456769962;12351.75296494083;12569;12689.87310126887;13066.346682666019;12940.41768120418;13282.80075798883;13567.796483052565;13635.181889086687;13903.304834758424;11700.239484433485;11760.353180574808;12023.879761202626;12252.75494490123;12270.754584908073;12518.37408129608;12728.74542509163;12744.872551274368;12893.484260629844;13591.320433978064;9515.857262141211;11072.667819965414;11213;11276.379799111128;11571.884281157081;10224.335418197794;10307.742306442398;10431.739206519898;11402.657920262405;11434.085273178292;9497.905020949978;9678.612855485702;9801.411915285105;10005.349652272578;10274.434906080238 16:31:58 INFO - PROCESS | 4161 | __end_tp_report 16:31:58 INFO - PROCESS | 4161 | __start_cc_report 16:31:58 INFO - PROCESS | 4161 | _x_x_mozilla_cycle_collect,323 16:31:58 INFO - PROCESS | 4161 | __end_cc_report 16:31:58 INFO - PROCESS | 4161 | __startTimestamp1462491118100__endTimestamp 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | ------- Summary: start ------- 16:31:58 INFO - PROCESS | 4161 | Number of tests: 6 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | [#0] dojo.html Cycles:165 Average:5440.07 Median:5710.06 stddev:733.56 (12.8%) stddev-sans-first:713.84 16:31:58 INFO - PROCESS | 4161 | Values: 3168.7 3187.3 3208.0 3234.3 3236.1 5406.8 5417.5 5455.4 5500.9 5567.8 5411.2 5412.5 5476.6 5487.3 5495.1 4882.9 4963.3 5084.5 5123.4 5180.0 673.6 4888.9 5015.0 5096.7 5138.4 4997.8 5047.1 5072.6 5073.2 5074.2 5113.1 5115.4 5120.7 5125.7 5149.0 4914.1 4969.4 5004.1 5014.3 5063.5 5386.7 5480.8 5503.1 5505.7 5541.7 5706.6 5958.3 5963.8 5970.9 5990.7 5748.7 5763.7 5767.5 5872.4 5879.2 5445.7 5510.9 5522.7 5522.8 5525.4 5848.6 5943.6 5986.6 6091.0 6099.0 5799.3 5886.4 5886.6 5891.6 5935.9 5942.0 5955.2 5958.4 5975.9 5984.6 5661.9 5741.7 5756.6 5928.1 5952.8 5774.7 5860.8 5861.3 5868.6 5899.1 4328.8 4335.0 4346.3 4350.5 4368.9 3927.9 3942.2 3975.9 3990.5 4016.9 5475.6 5479.2 5499.8 5533.1 5571.2 5840.0 5859.9 5860.9 5873.4 5877.7 5467.4 5580.5 5589.8 5669.0 5670.1 4888.9 4919.6 4926.0 4935.9 4970.0 6166.8 6167.2 6174.3 6179.9 6189.0 5798.3 5823.1 5837.2 5893.0 5927.0 6087.8 6095.9 6097.8 6101.6 6123.4 5675.5 5789.4 5819.4 5843.4 5852.3 5841.7 5918.1 5942.0 5956.3 5968.0 5710.1 5756.4 5760.4 5777.5 5800.2 5634.6 5749.8 5761.0 5787.3 5790.0 5665.7 5715.1 5730.6 5749.5 5786.3 5798.3 5838.4 5859.6 5887.1 5889.9 5535.1 5574.7 5599.1 5744.7 5746.0 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | [#1] ext.html Cycles:180 Average:53214.82 Median:22456.64 stddev:101091.76 (450.2%) stddev-sans-first:101298.11 16:31:58 INFO - PROCESS | 4161 | Values: 582.2 616.2 641.9 648.1 681.2 36482.3 40820.6 41039.8 41206.4 41324.4 40712.0 41460.8 41530.8 41716.4 41727.8 8817.0 8838.0 8865.8 8889.8 8890.9 8747.6 8816.4 8833.1 8878.5 8886.9 8834.0 8876.9 8903.8 8908.0 8941.7 8826.8 8831.7 8916.0 8923.2 8938.5 8736.6 8793.0 8806.9 8808.5 8814.3 34224.3 39415.2 39562.2 39623.6 39653.4 3486.9 3531.3 3788.1 4092.7 4245.4 30505.7 30559.0 30699.8 30848.7 32027.5 4271.8 4317.4 4390.7 4472.1 4513.7 255327.0 258675.0 260757.7 261631.9 262695.0 32398.5 33114.8 33265.7 34557.8 34987.0 36029.0 36420.1 36435.3 36441.0 36512.0 15022.5 15202.7 15208.8 15256.3 15327.5 12093.2 12114.8 12187.1 12204.3 12209.9 8614.4 8626.0 8656.6 8679.3 8721.3 3359.0 3389.1 3400.7 3412.2 3466.5 1091.3 1094.9 1122.5 1224.3 1235.1 31515.4 32354.4 32370.4 32597.8 32702.3 1132.7 1159.7 1182.5 1201.9 1247.8 16150.4 16198.9 16263.0 16433.8 16497.3 450514.0 452785.0 452917.0 458978.0 460879.0 55017.0 55421.4 55584.2 55687.7 55707.4 1427.9 1446.8 1453.8 1462.7 1484.5 30238.8 30270.5 30433.2 30469.4 30669.0 54915.5 57450.7 57725.7 62756.1 62802.0 400655.0 401932.0 405570.0 405871.0 405933.0 43525.8 43813.3 43823.8 44259.0 44416.8 48250.5 48477.3 48756.3 49558.8 49618.8 41257.6 41872.2 42017.4 42797.4 43392.6 41628.8 41794.8 41813.0 41847.0 42441.6 41897.8 42131.8 42208.2 42503.6 43024.0 36359.5 36802.0 37075.8 37425.4 38089.6 28711.6 28987.9 29119.0 29230.0 29360.0 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | [#2] jquery.html Cycles:180 Average:28065.65 Median:30641.73 stddev:69511.61 (226.9%) stddev-sans-first:69675.56 16:31:58 INFO - PROCESS | 4161 | Values: 394.9 399.4 400.3 401.0 425.9 13810.0 17817.9 18659.6 19395.6 19517.9 18525.3 18662.9 18932.1 19720.9 19804.2 5647.8 5722.7 5902.2 5903.1 5906.7 5541.2 5794.1 5799.9 5845.3 5848.6 5891.3 5898.9 5903.6 5907.5 5920.9 5421.2 5730.7 5751.3 5892.0 5896.1 5728.5 5734.7 5752.9 5755.3 5771.9 17610.6 21863.6 22604.4 22816.3 22830.2 2375.3 2444.9 2452.3 2491.2 3162.7 14330.9 14917.0 15303.5 15935.5 16275.1 2073.6 2115.7 2139.1 2158.5 2199.9 90366.0 91795.5 93327.0 94183.0 95787.5 16799.7 17385.7 17625.4 17743.3 17883.6 30424.5 30492.2 30791.2 31079.7 31133.7 9485.8 9995.1 10039.8 10045.5 10049.4 8292.1 8340.9 8345.9 8366.9 8483.9 8832.5 8927.1 8963.0 8985.1 9004.4 3475.9 3584.6 3586.7 3601.8 3611.8 960.0 986.6 1030.1 1091.6 1100.9 18906.6 19210.2 19365.3 19667.5 19871.8 1048.6 1054.5 1084.8 1086.7 1106.8 10647.8 11002.2 11035.7 11036.8 11081.8 406874.0 415876.0 422887.0 422917.0 423459.0 87934.0 92609.0 92670.0 93190.5 93885.1 1053.3 1072.4 1075.2 1096.7 1104.6 17134.5 17201.3 17289.6 17325.2 17382.7 36780.0 37509.6 37645.0 38457.6 38742.2 49615.3 51932.5 54416.0 56327.2 56673.4 8878.0 9009.4 9049.9 9087.1 9258.3 9875.4 13132.3 13360.7 13491.5 13599.0 10863.8 11428.2 11460.3 11665.2 11869.4 11458.9 11485.9 11487.1 11747.7 11777.1 11726.4 11769.2 11837.7 11856.3 11862.6 9001.8 9012.9 9069.4 9093.9 9135.2 11308.5 11651.5 11669.1 11793.4 11858.2 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | [#3] mootools.html Cycles:180 Average:5746.28 Median:4103.56 stddev:3256.06 (79.3%) stddev-sans-first:3250.00 16:31:58 INFO - PROCESS | 4161 | Values: 1560.7 1566.2 1764.7 1847.3 1852.9 7558.4 8041.4 8230.8 8765.5 8805.3 8031.4 8239.2 8326.3 8333.8 8411.3 2250.6 2290.4 2290.5 2291.3 2296.2 2239.1 2258.2 2286.2 2287.1 2292.1 2229.3 2235.8 2237.9 2291.2 2291.9 2217.0 2225.8 2252.8 2253.4 2254.0 2241.2 2269.5 2275.4 2276.0 2283.9 8380.6 8394.7 8398.8 8411.2 8425.0 4971.2 5155.5 5176.4 5184.1 5186.6 3233.5 3306.4 3464.7 3473.5 3503.3 1111.7 1117.5 1121.7 1132.4 1146.5 12060.5 12089.3 12094.2 12103.0 12120.2 4503.5 4542.6 4645.4 4648.7 4683.0 10008.9 10012.3 10013.7 10087.2 10093.6 5202.5 5264.2 5311.2 5336.4 5338.7 4316.9 4714.4 4746.0 4820.8 4935.5 3790.0 3884.7 3912.4 3916.6 3976.7 1253.4 1272.5 1273.4 1275.4 1280.4 912.3 1002.9 1009.0 1029.4 1048.9 5819.5 5863.5 6123.5 6125.8 6143.1 929.9 1028.1 1035.3 1043.7 1068.7 4061.6 4066.9 4076.1 4080.3 4126.8 10600.3 11018.3 11163.2 11164.3 11174.2 10937.3 10964.3 10988.3 11025.9 11089.7 8236.2 8310.9 8361.5 8363.9 8410.3 5791.3 5877.1 5910.6 5914.4 6047.2 9568.6 10168.0 10212.7 10236.1 10247.5 10005.1 10193.0 10193.3 10282.3 10379.4 6845.7 7224.1 7484.0 7692.5 7759.8 8453.9 8455.0 8456.9 8482.7 8547.0 7580.7 7799.5 7810.7 7884.0 7960.7 7616.8 7743.1 7750.2 7794.3 7836.5 7100.0 7148.3 7176.4 7176.9 7187.3 4888.0 4980.5 4985.3 5057.9 5079.3 4779.2 5038.7 5062.7 5104.4 5180.9 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | [#4] prototype.html Cycles:180 Average:4841.85 Median:5584.82 stddev:1429.83 (25.6%) stddev-sans-first:1393.31 16:31:58 INFO - PROCESS | 4161 | Values: 338.6 340.9 344.4 346.1 348.8 5605.4 5680.7 5688.5 5701.9 5742.4 5599.8 5608.0 5609.7 5632.2 5633.4 4153.6 4201.9 4275.5 4317.2 4327.4 4244.7 4275.4 4288.8 4298.2 4336.5 4325.2 4330.7 4332.2 4345.4 4351.3 4090.3 4261.9 4274.1 4307.4 4344.5 3697.4 3743.9 3759.4 3761.0 3762.5 5353.7 5361.9 5372.3 5414.4 5430.7 4597.6 4863.5 4868.6 4893.6 4935.5 5686.2 5771.9 5772.9 5774.6 5788.0 4033.1 4065.3 4071.9 4089.2 4099.4 5907.6 6001.5 6003.9 6027.9 6104.4 4159.9 4166.4 4183.6 4186.7 4198.1 4220.6 4245.9 4247.7 4247.7 4250.7 4675.0 4867.8 4869.2 4877.3 4892.3 5831.8 5849.7 5860.6 5910.3 5912.1 1436.4 1439.3 1448.8 1454.3 1456.6 921.3 922.7 933.6 1000.5 1010.8 5397.3 5426.3 5469.2 5484.9 5523.3 5818.9 5836.9 5848.6 5851.7 5857.2 5241.5 5372.8 5379.8 5430.7 5547.5 3036.2 3066.3 3070.1 3071.5 3074.4 5707.8 5766.6 5909.9 5917.9 5929.1 5776.6 5829.5 5846.0 5872.9 5915.3 5837.4 5858.7 5884.7 5889.8 5923.2 5524.9 5561.2 5682.8 5723.0 5796.4 5655.9 5683.5 5685.3 5712.2 5733.2 5836.6 5925.9 5974.7 5982.5 5990.0 4227.7 4246.6 4264.0 4279.6 4282.8 6138.4 6138.6 6144.2 6147.5 6160.9 5688.0 5866.7 5870.2 5908.3 5937.5 5847.5 5861.2 5872.8 5882.6 5914.2 5482.9 5695.7 5823.9 5865.8 5873.9 5917.9 5933.6 5944.6 5947.6 5951.6 5569.8 5740.9 5754.9 5806.9 5810.4 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - PROCESS | 4161 | [#5] yui.html Cycles:180 Average:14684.74 Median:45477.32 stddev:16837.93 (37.0%) stddev-sans-first:16852.58 16:31:58 INFO - PROCESS | 4161 | Values: 735.5 868.3 875.1 883.2 888.9 13997.2 14484.4 14486.6 14680.3 14797.3 13716.5 14303.6 14305.8 14306.3 14385.4 702.7 819.2 823.2 844.2 844.9 844.7 848.5 849.2 851.2 852.5 899.9 905.1 905.2 905.8 906.4 884.1 895.8 901.5 901.7 902.7 645.1 654.0 656.8 657.4 659.0 13696.9 13714.5 13764.2 13775.4 13846.8 13891.9 13948.4 13999.7 14006.7 14109.3 11369.7 11397.4 11529.1 11539.8 11788.7 3737.5 3814.7 3862.0 3893.1 4004.2 52138.2 53002.5 54603.2 55038.7 55190.2 10790.3 11190.7 11279.0 11305.9 11396.3 20412.2 20453.5 20490.9 20554.0 20835.0 7556.2 7628.4 7745.7 7800.6 8074.2 4487.9 4653.3 4668.2 4697.0 4837.8 3245.1 3305.1 3317.3 3323.9 3325.6 561.1 605.5 607.9 658.5 699.4 635.0 753.3 754.5 779.2 791.7 8780.5 9206.2 9216.9 9245.7 9268.3 665.2 768.4 774.2 777.4 782.3 4097.2 4262.3 4301.8 4327.3 4365.2 55621.0 55848.0 56023.0 61053.0 61481.0 54200.0 54313.7 54330.7 56017.0 57056.0 51347.0 51732.5 52180.7 52193.7 52510.2 8964.3 9003.5 9051.0 9189.8 9237.8 32818.7 32939.2 33285.7 33791.7 34077.8 43344.1 44602.0 44734.3 45190.1 45764.5 12155.3 12351.8 12569.0 12689.9 13066.3 12940.4 13282.8 13567.8 13635.2 13903.3 11700.2 11760.4 12023.9 12252.8 12270.8 12518.4 12728.7 12744.9 12893.5 13591.3 9515.9 11072.7 11213.0 11276.4 11571.9 10224.3 10307.7 10431.7 11402.7 11434.1 9497.9 9678.6 9801.4 10005.3 10274.4 16:31:58 INFO - PROCESS | 4161 | -------- Summary: end -------- 16:31:58 INFO - PROCESS | 4161 | 16:31:58 INFO - TEST-INFO | 4161: exit 0 16:31:58 INFO - TEST-OK | dromaeo_css | took 1109507ms 16:31:58 INFO - TEST-START | kraken 16:31:58 INFO - operating with platform_type : linux_ 16:31:58 INFO - Initialising browser for kraken test... 16:31:58 INFO - TEST-INFO | started process 4387 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQjEhsa/profile http://localhost:38154/getInfo.html) 16:32:02 INFO - PROCESS | 4387 | 16:32:02 INFO - PROCESS | 4387 | (process:4458): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:32:02 INFO - PROCESS | 4387 | __metrics Screen width/height:1600/1200 16:32:02 INFO - PROCESS | 4387 | colorDepth:24 16:32:02 INFO - PROCESS | 4387 | Browser inner width/height: 1024/697 16:32:02 INFO - PROCESS | 4387 | __metrics 16:32:03 ERROR - PROCESS | 4387 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 16:32:03 INFO - TEST-INFO | 4387: exit 0 16:32:03 INFO - Browser initialized. 16:32:03 INFO - Running cycle 1/1 for kraken test... 16:32:03 INFO - TEST-INFO | started process 4509 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQjEhsa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:05 INFO - PROCESS | 4509 | 16:32:05 INFO - PROCESS | 4509 | (process:4571): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:32:15 INFO - PROCESS | 4509 | RSS: Main: 163377152 16:32:15 INFO - PROCESS | 4509 | 16:32:18 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38154/tests/kraken/driver.html?testName=audio-beat-detection) 16:32:19 INFO - PROCESS | 4509 | RSS: Main: 167546880 16:32:19 INFO - PROCESS | 4509 | 16:32:21 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38154/tests/kraken/driver.html?testName=audio-dft) 16:32:22 INFO - PROCESS | 4509 | RSS: Main: 167718912 16:32:22 INFO - PROCESS | 4509 | 16:32:25 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38154/tests/kraken/driver.html?testName=audio-fft) 16:32:26 INFO - PROCESS | 4509 | RSS: Main: 162365440 16:32:26 INFO - PROCESS | 4509 | 16:32:28 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38154/tests/kraken/driver.html?testName=audio-oscillator) 16:32:29 INFO - PROCESS | 4509 | RSS: Main: 163135488 16:32:29 INFO - PROCESS | 4509 | 16:32:31 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38154/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:32:31 INFO - PROCESS | 4509 | RSS: Main: 163495936 16:32:31 INFO - PROCESS | 4509 | 16:32:34 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38154/tests/kraken/driver.html?testName=imaging-darkroom) 16:32:35 INFO - PROCESS | 4509 | RSS: Main: 163631104 16:32:35 INFO - PROCESS | 4509 | 16:32:38 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38154/tests/kraken/driver.html?testName=imaging-desaturate) 16:32:39 INFO - PROCESS | 4509 | RSS: Main: 163954688 16:32:39 INFO - PROCESS | 4509 | 16:32:42 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38154/tests/kraken/driver.html?testName=json-parse-financial) 16:32:43 INFO - PROCESS | 4509 | RSS: Main: 164274176 16:32:43 INFO - PROCESS | 4509 | 16:32:44 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38154/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:32:45 INFO - PROCESS | 4509 | RSS: Main: 164085760 16:32:45 INFO - PROCESS | 4509 | 16:32:46 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:47 INFO - PROCESS | 4509 | RSS: Main: 163991552 16:32:47 INFO - PROCESS | 4509 | 16:32:49 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:50 INFO - PROCESS | 4509 | RSS: Main: 164376576 16:32:50 INFO - PROCESS | 4509 | 16:32:52 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:53 INFO - PROCESS | 4509 | RSS: Main: 164524032 16:32:53 INFO - PROCESS | 4509 | 16:32:56 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:32:57 INFO - PROCESS | 4509 | RSS: Main: 164519936 16:32:57 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | Cycle 1(1): loaded http://localhost:38154/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38154/tests/kraken/driver.html?testName=ai-astar) 16:32:58 INFO - PROCESS | 4509 | RSS: Main: 165949440 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | __start_tp_report 16:32:58 INFO - PROCESS | 4509 | _x_x_mozilla_page_load 16:32:58 INFO - PROCESS | 4509 | _x_x_mozilla_page_load_details 16:32:58 INFO - PROCESS | 4509 | |i|pagename|runs| 16:32:58 INFO - PROCESS | 4509 | |0;ai-astar;109;116;107;112;114;114;107;113;112;114 16:32:58 INFO - PROCESS | 4509 | |1;audio-beat-detection;163;125;154;192;132;160;152;138;184;131 16:32:58 INFO - PROCESS | 4509 | |2;audio-dft;163;163;161;162;163;164;167;165;162;161 16:32:58 INFO - PROCESS | 4509 | |3;audio-fft;77;76;78;162;78;76;76;74;171;75 16:32:58 INFO - PROCESS | 4509 | |4;audio-oscillator;91;94;145;93;92;93;139;91;100;158 16:32:58 INFO - PROCESS | 4509 | |5;imaging-gaussian-blur;117;118;117;118;117;117;118;117;118;117 16:32:58 INFO - PROCESS | 4509 | |6;imaging-darkroom;146;150;151;148;146;148;146;146;147;145 16:32:58 INFO - PROCESS | 4509 | |7;imaging-desaturate;97;97;96;99;95;96;95;96;96;95 16:32:58 INFO - PROCESS | 4509 | |8;json-parse-financial;59;59;59;58;59;59;59;81;62;65 16:32:58 INFO - PROCESS | 4509 | |9;json-stringify-tinderbox;59;59;60;59;60;58;59;59;59;61 16:32:58 INFO - PROCESS | 4509 | |10;stanford-crypto-aes;79;79;79;80;81;81;89;82;79;85 16:32:58 INFO - PROCESS | 4509 | |11;stanford-crypto-ccm;127;148;135;127;129;128;124;131;201;137 16:32:58 INFO - PROCESS | 4509 | |12;stanford-crypto-pbkdf2;170;180;168;172;173;167;171;176;168;178 16:32:58 INFO - PROCESS | 4509 | |13;stanford-crypto-sha256-iterative;65;64;67;65;65;63;63;62;63;63 16:32:58 INFO - PROCESS | 4509 | __end_tp_report 16:32:58 INFO - PROCESS | 4509 | __start_cc_report 16:32:58 INFO - PROCESS | 4509 | _x_x_mozilla_cycle_collect,866 16:32:58 INFO - PROCESS | 4509 | __end_cc_report 16:32:58 INFO - PROCESS | 4509 | __startTimestamp1462491178956__endTimestamp 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | ------- Summary: start ------- 16:32:58 INFO - PROCESS | 4509 | Number of tests: 14 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.50 stddev:3.12 (2.7%) stddev-sans-first:3.14 16:32:58 INFO - PROCESS | 4509 | Values: 109.0 116.0 107.0 112.0 114.0 114.0 107.0 113.0 112.0 114.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#1] audio-beat-detection Cycles:10 Average:153.10 Median:157.00 stddev:22.53 (14.3%) stddev-sans-first:23.61 16:32:58 INFO - PROCESS | 4509 | Values: 163.0 125.0 154.0 192.0 132.0 160.0 152.0 138.0 184.0 131.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.85 (1.1%) stddev-sans-first:1.96 16:32:58 INFO - PROCESS | 4509 | Values: 163.0 163.0 161.0 162.0 163.0 164.0 167.0 165.0 162.0 161.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#3] audio-fft Cycles:10 Average:94.30 Median:76.00 stddev:38.13 (50.2%) stddev-sans-first:39.93 16:32:58 INFO - PROCESS | 4509 | Values: 77.0 76.0 78.0 162.0 78.0 76.0 76.0 74.0 171.0 75.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#4] audio-oscillator Cycles:10 Average:109.60 Median:91.50 stddev:26.56 (29.0%) stddev-sans-first:27.30 16:32:58 INFO - PROCESS | 4509 | Values: 91.0 94.0 145.0 93.0 92.0 93.0 139.0 91.0 100.0 158.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:32:58 INFO - PROCESS | 4509 | Values: 117.0 118.0 117.0 118.0 117.0 117.0 118.0 117.0 118.0 117.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:1.95 (1.3%) stddev-sans-first:2.01 16:32:58 INFO - PROCESS | 4509 | Values: 146.0 150.0 151.0 148.0 146.0 148.0 146.0 146.0 147.0 145.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:1.23 (1.3%) stddev-sans-first:1.27 16:32:58 INFO - PROCESS | 4509 | Values: 97.0 97.0 96.0 99.0 95.0 96.0 95.0 96.0 96.0 95.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:59.00 stddev:6.99 (11.9%) stddev-sans-first:7.33 16:32:58 INFO - PROCESS | 4509 | Values: 59.0 59.0 59.0 58.0 59.0 59.0 59.0 81.0 62.0 65.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 16:32:58 INFO - PROCESS | 4509 | Values: 59.0 59.0 60.0 59.0 60.0 58.0 59.0 59.0 59.0 61.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:3.27 (4.0%) stddev-sans-first:3.35 16:32:58 INFO - PROCESS | 4509 | Values: 79.0 79.0 79.0 80.0 81.0 81.0 89.0 82.0 79.0 85.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:133.00 stddev:22.95 (17.3%) stddev-sans-first:23.95 16:32:58 INFO - PROCESS | 4509 | Values: 127.0 148.0 135.0 127.0 129.0 128.0 124.0 131.0 201.0 137.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:172.50 stddev:4.45 (2.6%) stddev-sans-first:4.64 16:32:58 INFO - PROCESS | 4509 | Values: 170.0 180.0 168.0 172.0 173.0 167.0 171.0 176.0 168.0 178.0 16:32:58 INFO - PROCESS | 4509 | 16:32:58 INFO - PROCESS | 4509 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.50 stddev:1.49 (2.3%) stddev-sans-first:1.54 16:32:58 INFO - PROCESS | 4509 | Values: 65.0 64.0 67.0 65.0 65.0 63.0 63.0 62.0 63.0 63.0 16:32:58 INFO - PROCESS | 4509 | -------- Summary: end -------- 16:32:58 INFO - PROCESS | 4509 | 16:32:59 INFO - TEST-INFO | 4509: exit 0 16:32:59 INFO - TEST-OK | kraken | took 61053ms 16:32:59 INFO - SUITE-END | took 1170s 16:33:00 INFO - Completed test suite (00:19:30) 16:33:00 INFO - javascript benchmark 16:33:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3168.6831316868315, 3187.2828613561965, 3207.9518807217896, 3234.3207926335467, 3236.093893709761, 5406.756695948687, 5417.45825417458, 5455.427180146082, 5500.862478438041, 5567.805126820562, 5411.215373770801, 5412.512873841353, 5476.58925580581, 5487.341519017724, 5495.120780675093, 4882.853514394556, 4963.305137280783, 5084.516970887778, 5123.359580052494, 5180.0, 673.5844734392284, 4888.877778055541, 5014.974925125369, 5096.668716533413, 5138.434772175057, 4997.775100120485, 5047.066292735855, 5072.644914855961, 5073.188289873621, 5074.188129899217, 5113.130767769489, 5115.386153661547, 5120.692758434506, 5125.666831655931, 5149.0, 4914.11545921734, 4969.378827646544, 5004.074246264453, 5014.2979982802435, 5063.493650634944, 5386.73066346685, 5480.835574932758, 5503.11950087988, 5505.724713764296, 5541.722913854332, 5706.600537962344, 5958.314793798725, 5963.761449542013, 5970.948125901355, 5990.670513121777, 5748.655080695172, 5763.711814409305, 5767.509761670263, 5872.442117998783, 5879.176915231871, 5445.727713614343, 5510.9448905510835, 5522.723863806834, 5522.834314970557, 5525.4474552544825, 5848.619839710405, 5943.613665111754, 5986.551008674367, 6090.969545152246, 6098.969505152535, 5799.2750906136735, 5886.411358864165, 5886.558508111909, 5891.587588868823, 5935.940640593539, 5941.970290148522, 5955.225820643289, 5958.433948774859, 5975.850603734854, 5984.640921544721, 5661.915071273934, 5741.655500669973, 5756.5682573807135, 5928.140419639129, 5952.821415357546, 5774.682392468455, 5860.765569377262, 5861.296644402699, 5868.618539794985, 5899.115132730039, 4328.761918094472, 4335.046289816235, 4346.261135606955, 4350.543192964757, 4368.934465983013, 3927.8625248116177, 3942.2312649033165, 3975.940360894589, 3990.4812374391145, 4016.879493615196, 5475.6440831345835, 5479.205515200274, 5499.780008799603, 5533.059379905426, 5571.164325351148, 5839.970800145972, 5859.882802343929, 5860.941390586039, 5873.442023007808, 5877.735501902424, 5467.398586155519, 5580.525655319262, 5589.804356847572, 5669.036263835075, 5670.121131224584, 4888.902221955541, 4919.606431485473, 4925.975370123127, 4935.90128197434, 4970.030843985388, 6166.845828854314, 6167.198264225622, 6174.289956655042, 6179.907301390483, 6188.969055154786, 5798.33319168301, 5823.126531020381, 5837.153612726216, 5893.027650437802, 5926.970365148147, 6087.756489740361, 6095.8780824382375, 6097.75608975636, 6101.60339577926, 6123.4488895999575, 5675.545956323402, 5789.421057894177, 5819.359870414336, 5843.386444423274, 5852.326982397079, 5841.678707671119, 5918.082697181943, 5941.970290148522, 5956.285245770535, 5968.045112781934, 5710.057840456444, 5756.366799651951, 5760.395158508296, 5777.508911742549, 5800.24596802413, 5634.6337488063145, 5749.8275051748515, 5761.020626493422, 5787.305523337226, 5790.044642634087, 5665.688387138747, 5715.085586306171, 5730.598858079894, 5749.511291540266, 5786.3056433228285, 5798.33319168301, 5838.386969368155, 5859.560532959959, 5887.087501437284, 5889.852753681193, 5535.11438169907, 5574.749136288796, 5599.132134519156, 5744.712764361849, 5745.971270143623], "value": 5392.0281164441285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.2372691773777, 616.2235583165212, 641.9325970773069, 648.1185587600864, 681.1621705302475, 36482.270354592925, 40820.59179408205, 41039.79480102595, 41206.38190427146, 41324.38013429797, 40712.0, 41460.79269603648, 41530.7923460383, 41716.37425438621, 41727.79136104315, 8817.030126686075, 8838.0, 8865.822683546325, 8889.822203555925, 8890.911090889073, 8747.606357713918, 8816.382853200279, 8833.11668833118, 8878.467291962503, 8886.86669699955, 8833.95583022084, 8876.911230887674, 8903.77740556488, 8908.0, 8941.687040953568, 8826.779330516723, 8831.73504794854, 8915.955420222923, 8923.241524470428, 8938.508382038985, 8736.60685269158, 8792.956035219782, 8806.86789698155, 8808.458519759017, 8814.25078868297, 34224.31551368959, 39415.211695765924, 39562.20875582501, 39623.603763962565, 39653.40519892204, 3486.877959271413, 3531.329047480996, 3788.052986753312, 4092.6930480214105, 4245.448091748084, 30505.69494305051, 30559.0, 30699.846500767577, 30848.691513085032, 32027.51958720621, 4271.829126834954, 4317.438732964694, 4390.670699697504, 4472.105578884204, 4513.7066090704, 255327.0, 258675.0, 260757.69621151962, 261631.91840407904, 262695.0, 32398.514022289684, 33114.834425827714, 33265.667343326255, 34557.82721086378, 34987.0, 36029.0, 36420.08949776277, 36435.27129457396, 36441.0, 36512.0, 15022.474213402478, 15202.695946081016, 15208.847911520743, 15256.313465894062, 15327.463538776312, 12093.153479256544, 12114.81827772584, 12187.146899717112, 12204.328761918003, 12209.877901221053, 8614.44006139599, 8626.0, 8656.61045252965, 8679.349048821237, 8721.258693011165, 3358.983205083959, 3389.084947064279, 3400.7417255615583, 3412.2322477442376, 3466.4626982817704, 1091.307020042263, 1094.9014588687057, 1122.4612186150694, 1224.302147775777, 1235.123062625528, 31515.36969260602, 32354.35291294208, 32370.35259294801, 32597.837010814794, 32702.345953080807, 1132.6885106595587, 1159.6984783956232, 1182.4501606752729, 1201.9459124339426, 1247.7904747678406, 16150.35398584067, 16198.919005405134, 16263.0, 16433.753493697604, 16497.257623406975, 450514.0, 452785.0, 452917.0, 458978.0, 460879.0, 55017.0, 55421.445785541626, 55584.16623750647, 55687.72156139275, 55707.442925571035, 1427.8505802828822, 1446.7902154187793, 1453.7673972164403, 1462.714770619719, 1484.5249520153448, 30238.84880575583, 30270.54594181045, 30433.23916902095, 30469.390612188075, 30669.0, 54915.45084549103, 57450.712746437675, 57725.71137144455, 62756.117316478754, 62802.0, 400655.0, 401932.0, 405570.0, 405871.0, 405933.0, 43525.78237108921, 43813.34279985739, 43823.780881095394, 44259.0, 44416.777916110215, 48250.517494824606, 48477.272840906706, 48756.26865596948, 49558.752206238736, 49618.751906240235, 41257.58742412538, 41872.1625567493, 42017.36973945332, 42797.35803962881, 43392.56607433885, 41628.79185604052, 41794.79102604589, 41813.00591628139, 41847.0, 42441.575584243765, 41897.79051104847, 42131.789341053096, 42208.15583688248, 42503.5749642512, 43024.0, 36359.45460818037, 36802.0, 37075.8146209278, 37425.4386184202, 38089.6191038086, 28711.569326459703, 28987.855060725404, 29119.0, 29230.0, 29360.0], "value": 18748.564504650425, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.8835093647374, 399.357035173371, 400.3394399241251, 400.95990400959926, 425.9236223410872, 13810.0, 17817.910910445447, 18659.626807463843, 19395.612087758236, 19517.902410487965, 18525.2589896404, 18662.906685466587, 18932.148053337598, 19720.901395493, 19804.207831686752, 5647.802326918559, 5722.7424765885535, 5902.173695682608, 5903.114532820068, 5906.704664766766, 5541.168824676311, 5794.130880367958, 5799.942000580004, 5845.298564172305, 5848.619839710405, 5891.322497912752, 5898.852528686774, 5903.557233207505, 5907.468327850493, 5920.904632642953, 5421.159720243369, 5730.713464326767, 5751.25233719618, 5892.027815410452, 5896.056630939051, 5728.541716662657, 5734.713264336809, 5752.913706294409, 5755.338136114358, 5771.884562308773, 17610.647787044316, 21863.562728745495, 22604.43488912774, 22816.31551053471, 22830.200942967083, 2375.3111597636666, 2444.8899799509063, 2452.301094188148, 2491.2152671908293, 3162.6521082681115, 14330.856691433057, 14917.0, 15303.540893773205, 15935.521934341989, 16275.128360238607, 2073.5749171419825, 2115.6932244824416, 2139.0801955159277, 2158.5143342747915, 2199.9010044548036, 90366.0, 91795.54102229513, 93327.0, 94183.0, 95787.52106239424, 16799.748003779954, 17385.65228695419, 17625.383111591033, 17743.290268389377, 17883.642327153386, 30424.54363184554, 30492.237694057825, 30791.23021924425, 31079.68920310768, 31133.68866311353, 9485.762855928657, 9995.050470205319, 10039.799204015879, 10045.497725113715, 10049.447280399501, 8292.087870334259, 8340.916590834135, 8345.874811877828, 8366.916330836613, 8483.915160848435, 8832.470051796912, 8927.10728927115, 8963.0, 8985.101489850962, 9004.36969412148, 3475.8957231283102, 3584.605693373727, 3586.6771990520456, 3601.7658852174527, 3611.7652352596997, 959.9952000239954, 986.5609803637456, 1030.1038096855784, 1091.5579190427732, 1100.9394483303495, 18906.62186756257, 19210.231590736494, 19365.322213722735, 19667.508312292022, 19871.801281986995, 1048.5753269925692, 1054.4569546683465, 1084.7505073832904, 1086.7065892209218, 1106.756513567014, 10647.84028239577, 11002.229843911009, 11035.668929932115, 11036.779264414827, 11081.833772493419, 406874.0, 415876.0, 422887.0, 422917.0, 423459.0, 87934.0, 92609.0, 92670.0, 93190.53404732933, 93885.06114938763, 1053.3206082077018, 1072.3780207479488, 1075.1989767623015, 1096.660035389032, 1104.5581767293147, 17134.485965421056, 17201.311947521957, 17289.56776080608, 17325.220365083853, 17382.739258910875, 36780.0, 37509.624903751705, 37645.0, 38457.615423846524, 38742.2251554973, 49615.255771164186, 51932.48067519276, 54416.0, 56327.15509267282, 56673.433265668464, 8878.0, 9009.369344145847, 9049.864252036094, 9087.09129087086, 9258.305627077862, 9875.358101723365, 13132.343382831012, 13360.665983350495, 13491.527796527467, 13599.0, 10863.837042444527, 11428.200025998432, 11460.338250953731, 11665.24175928562, 11869.40652967331, 11458.885411145782, 11485.885141148481, 11487.138464615355, 11747.706307342043, 11777.057835373322, 11726.355050471964, 11769.234999724991, 11837.704057398634, 11856.288622682667, 11862.644120676394, 9001.819963600823, 9012.86480702803, 9069.36514444009, 9093.909060909571, 9135.223506002065, 11308.49111789955, 11651.53393864236, 11669.066474682175, 11793.41032948332, 11858.229215100991], "value": 10103.780807721916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.656655535782, 1566.248200863585, 1764.7352897065439, 1847.3072597775833, 1852.8888266704007, 7558.395328373731, 8041.43709940303, 8230.75307740768, 8765.47407155571, 8805.295576353892, 8031.357491400689, 8239.217274358942, 8326.333893288538, 8333.833323333529, 8411.285040771541, 2250.628646273363, 2290.4159439342943, 2290.541891621674, 2291.32405940248, 2296.196331284054, 2239.070785623964, 2258.22091378475, 2286.199830059474, 2287.0737351372723, 2292.128990983424, 2229.308914236584, 2235.8099561537288, 2237.8545394549383, 2291.1522736587403, 2291.9083236670513, 2217.0355895185567, 2225.766294539066, 2252.7972482476657, 2253.4141123307977, 2253.9774602253933, 2241.1595651630637, 2269.489364892886, 2275.4311422144465, 2275.954480910389, 2283.908643654252, 8380.622871970776, 8394.748157555283, 8398.83202335956, 8411.200935911078, 8424.957875210646, 4971.204607262821, 5155.484451554852, 5176.352955880515, 5184.092783762826, 5186.611004174702, 3233.466478031122, 3306.371789360038, 3464.688178063961, 3473.4789781532972, 3503.2818272254126, 1111.6887271563894, 1117.5194666293492, 1121.685927940178, 1132.416805345248, 1146.4611632532697, 12060.517579296818, 12089.335086570236, 12094.213876098114, 12103.0, 12120.212186207957, 4503.482099558527, 4542.568455996675, 4645.442546894395, 4648.697834640738, 4683.0, 10008.949955250322, 10012.299139060342, 10013.699589012342, 10087.243456740774, 10093.646722364829, 5202.531772140525, 5264.184051472028, 5311.203319502106, 5336.4129945705945, 5338.652987555796, 4316.935245971313, 4714.38712967312, 4746.0, 4820.807167713252, 4935.481774413706, 3789.962100379016, 3884.6503814656817, 3912.4131380293184, 3916.6475017248013, 3976.661983731415, 1253.367049639926, 1272.497363541396, 1273.38877338876, 1275.387813849339, 1280.3982128399637, 912.3020889019905, 1002.9197664186847, 1009.0313299232819, 1029.4080903480528, 1048.9003544663244, 5819.505342045889, 5863.472287494146, 6123.540734444845, 6125.785597504155, 6143.109249158936, 929.8884133903974, 1028.1209565821264, 1035.342557475993, 1043.7077618266896, 1068.7274744939991, 4061.593840615915, 4066.877993660195, 4076.062505623723, 4080.2859499587453, 4126.824858308015, 10600.25798194119, 11018.283811552223, 11163.162762792823, 11164.274322169033, 11174.161937854693, 10937.289076210021, 10964.342139471657, 10988.340699558052, 11025.944870275598, 11089.667309980552, 8236.176382361717, 8310.875336869953, 8361.498310101291, 8363.874541881876, 8410.285125764378, 5791.333996590446, 5877.059670452878, 5910.556708246812, 5914.4381283777975, 6047.153398524298, 9568.61725530971, 10168.0, 10212.744681383025, 10236.129928955825, 10247.538860751136, 10005.149562287286, 10193.0, 10193.337433066827, 10282.280240383101, 10379.429131397847, 6845.726170953106, 7224.13310402755, 7484.0, 7692.499987500794, 7759.806004849924, 8453.915460845314, 8455.0, 8456.915430845613, 8482.703105391281, 8546.957265213634, 7580.658870350958, 7799.5320280783335, 7810.687572497264, 7883.960580197062, 7960.721374751856, 7616.8476630468185, 7743.070831500256, 7750.224977502205, 7794.2985131338455, 7836.529808211526, 7100.005999160018, 7148.28517148281, 7176.425885929218, 7176.856462870818, 7187.281271872771, 4887.975560122177, 4980.526849949249, 4985.326980857538, 5057.898842023213, 5079.314292570456, 4779.187538118459, 5038.672486288379, 5062.746862656779, 5104.3619547556555, 5180.922286165786], "value": 4619.67829784671, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.59199664404395, 340.90965894038084, 344.4110570923721, 346.1346633416459, 348.77678285897014, 5605.355384130826, 5680.687562184078, 5688.459596338341, 5701.942980570193, 5742.368339482654, 5599.804006859761, 5607.971960140204, 5609.747561359738, 5632.239647647566, 5633.408492108331, 4153.646940010103, 4201.894952626193, 4275.529691733922, 4317.201317756209, 4327.372530983005, 4244.660427165834, 4275.35869619558, 4288.76411797351, 4298.183345164424, 4336.544662810406, 4325.156594464081, 4330.653547716191, 4332.176886391576, 4345.413369195166, 4351.26028575143, 4090.3455447128613, 4261.87214383569, 4274.119387469504, 4307.440032795748, 4344.543822898598, 3697.4084146536693, 3743.887683369503, 3759.3984962405884, 3761.0409345616968, 3762.5484941806885, 5353.705546194958, 5361.919571206435, 5372.301600791872, 5414.43148469409, 5430.67415955044, 4597.60920321773, 4863.465018847924, 4868.6124454530645, 4893.559579637849, 4935.457099719028, 5686.175504551817, 5771.855703607402, 5772.855678608026, 5774.624649397818, 5787.971060144672, 4033.1127152026506, 4065.3088974874045, 4071.9389209161886, 4089.243489954338, 4099.364598487239, 5907.556933229982, 6001.549883758736, 6003.909941350883, 6027.914975304443, 6104.389561043905, 4159.8960025999595, 4166.437530933345, 4183.6234738873645, 4186.748795072244, 4198.06888831135, 4220.556841531657, 4245.936310955338, 4247.702660813775, 4247.745135291892, 4250.744955302692, 4674.984634910464, 4867.829625963074, 4869.245266983623, 4877.317175595419, 4892.339534162842, 5831.79588714393, 5849.678267695318, 5860.619059761102, 5910.320313163955, 5912.142739302777, 1436.4469679173594, 1439.3450979804163, 1448.7754398068105, 1454.2655958740768, 1456.5994351553409, 921.3228277216241, 922.7324076017879, 933.5798890499302, 1000.4697510319543, 1010.8332125199407, 5397.298351214396, 5426.321709786277, 5469.179623056494, 5484.890302193934, 5523.309586301712, 5818.854528636733, 5836.9124463133085, 5848.561357898175, 5851.707414629252, 5857.20927674767, 5241.475852414805, 5372.838814835562, 5379.821172425174, 5430.728463576806, 5547.5007249347755, 3036.2409397650586, 3066.3254084101463, 3070.063630592646, 3071.5239137933654, 3074.400491904057, 5707.771689132472, 5766.596338256366, 5909.87712334655, 5917.911231331534, 5929.0513517836935, 5776.566757493205, 5829.504492118217, 5845.970770146122, 5872.882542349214, 5915.260592425947, 5837.416258374214, 5858.677772722541, 5884.705764711747, 5889.823305300848, 5923.229980102646, 5524.91712624319, 5561.221428999862, 5682.7782026864425, 5722.971385143048, 5796.42035796417, 5655.858603534944, 5683.545316374597, 5685.271069886395, 5712.200291959046, 5733.197352370755, 5836.628392327714, 5925.933332000282, 5974.731137098928, 5982.52139828804, 5990.0, 4227.725197862129, 4246.64107485614, 4264.040590867155, 4279.550647182001, 4282.78586070689, 6138.355472675305, 6138.570300078952, 6144.201253836973, 6147.508199344131, 6160.907586386118, 5687.971560142173, 5866.735996880066, 5870.207521984648, 5908.261467316585, 5937.524998000236, 5847.532197424281, 5861.179434879035, 5872.765089396547, 5882.617629854046, 5914.201582786269, 5482.862928426821, 5695.658260504383, 5823.912641310299, 5865.765369385177, 5873.911891321548, 5917.881642367042, 5933.614315069679, 5944.554158438221, 5947.553933455095, 5951.613145145551, 5569.832905012856, 5740.913886291793, 5754.9424505754405, 5806.912896306474, 5810.360860305447], "value": 4383.13994707351, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.4814855526854, 868.3140319147874, 875.1292463998315, 883.2139395937612, 888.9466632002083, 13997.16017038977, 14484.420623175074, 14486.637834054154, 14680.265986700675, 14797.3341199646, 13716.451341946311, 14303.642408939755, 14305.78541321881, 14306.284685765697, 14385.424583016693, 702.6802804723839, 819.2217393476207, 823.1644880446352, 844.1684940333766, 844.8648216285395, 844.679021971653, 848.5248260973874, 849.180540778149, 851.1913682002091, 852.5140669818206, 899.9235065019449, 905.0587389115312, 905.1988989744058, 905.8143080668447, 906.374601524949, 884.0938038510513, 895.7939673875013, 901.4501154295874, 901.733988473402, 902.6660135749748, 645.0549944331577, 653.9705713242915, 656.7996760987896, 657.4477438951257, 659.0147729144911, 13696.93151534246, 13714.51999180024, 13764.17414955096, 13775.448982040707, 13846.792298115537, 13891.861081389157, 13948.442062317496, 13999.720005599831, 14006.71986560263, 14109.294535273195, 11369.658910232707, 11397.373144477051, 11529.135314851335, 11539.769204615945, 11788.705282367924, 3737.5141231639986, 3814.7329686921926, 3861.9613803861885, 3893.0851249956236, 4004.2191772604356, 52138.21792673113, 53002.469975300526, 54603.18095228575, 55038.724806376114, 55190.17214741782, 10790.298630588986, 11190.720231994103, 11279.041281491001, 11305.943470282597, 11396.316221026766, 20412.183512659623, 20453.48866278355, 20490.897545512176, 20554.0, 20835.0, 7556.168821429638, 7628.427867909818, 7745.728899488491, 7800.648970796327, 8074.152214017561, 4487.910241795146, 4653.255479123324, 4668.15973124834, 4696.95303046972, 4837.782299796518, 3245.075153581218, 3305.058058453329, 3317.336532693471, 3323.8670453182085, 3325.584301962255, 561.1302481154195, 605.4883623338272, 607.9392060793886, 658.5159907468008, 699.3635791429774, 634.9936500634934, 753.2806170107534, 754.4718696912183, 779.1546172402893, 791.7387262203459, 8780.517071561126, 9206.217471514863, 9216.861747073799, 9245.676401325922, 9268.25853931684, 665.2216906219735, 768.4428789127837, 774.1755030892082, 777.4014009212874, 782.3154739602847, 4097.221527909747, 4262.339337402707, 4301.78491075445, 4327.2643650579475, 4365.214261432972, 55621.0, 55848.0, 56023.0, 61053.0, 61481.0, 54200.0, 54313.72843135759, 54330.72834635802, 56017.0, 57056.0, 51347.0, 51732.48267517277, 52180.73909630428, 52193.7390313046, 52510.21234681483, 8964.282857371525, 9003.549822508718, 9051.0, 9189.77025574366, 9237.769055773659, 32818.67181328156, 32939.17652058718, 33285.66714332825, 33791.66208337984, 34077.82961085278, 43344.133117336845, 44602.0, 44734.31594104364, 45190.09619807651, 45764.54235457603, 12155.331456769962, 12351.75296494083, 12569.0, 12689.87310126887, 13066.346682666019, 12940.41768120418, 13282.80075798883, 13567.796483052565, 13635.181889086687, 13903.304834758424, 11700.239484433485, 11760.353180574808, 12023.879761202626, 12252.75494490123, 12270.754584908073, 12518.37408129608, 12728.74542509163, 12744.872551274368, 12893.484260629844, 13591.320433978064, 9515.857262141211, 11072.667819965414, 11213.0, 11276.379799111128, 11571.884281157081, 10224.335418197794, 10307.742306442398, 10431.739206519898, 11402.657920262405, 11434.085273178292, 9497.905020949978, 9678.612855485702, 9801.411915285105, 10005.349652272578, 10274.434906080238], "value": 6763.082076656508, "unit": "score"}], "name": "dromaeo_css", "value": 7204.956391193753, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 107.0, 112.0, 114.0, 114.0, 107.0, 113.0, 112.0, 114.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 125.0, 154.0, 192.0, 132.0, 160.0, 152.0, 138.0, 184.0, 131.0], "value": 153.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 162.0, 163.0, 164.0, 167.0, 165.0, 162.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 78.0, 162.0, 78.0, 76.0, 76.0, 74.0, 171.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 145.0, 93.0, 92.0, 93.0, 139.0, 91.0, 100.0, 158.0], "value": 109.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 150.0, 151.0, 148.0, 146.0, 148.0, 146.0, 146.0, 147.0, 145.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 99.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 81.0, 62.0, 65.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 60.0, 58.0, 59.0, 59.0, 59.0, 61.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 80.0, 81.0, 81.0, 89.0, 82.0, 79.0, 85.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 148.0, 135.0, 127.0, 129.0, 128.0, 124.0, 131.0, 201.0, 137.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 180.0, 168.0, 172.0, 173.0, 167.0, 171.0, 176.0, 168.0, 178.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 67.0, 65.0, 65.0, 63.0, 63.0, 62.0, 63.0, 63.0], "value": 64.0, "unit": "score"}], "name": "kraken", "value": 1570.5, "alertThreshold": 2.0}]} 16:33:00 INFO - Return code: 0 16:33:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:33:00 ERROR - # TBPL SUCCESS # 16:33:00 INFO - Running post-action listener: _resource_record_post_action 16:33:00 INFO - Running post-run listener: _resource_record_post_run 16:33:01 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 553426944; Read time: 8; Write time: 458028 16:33:01 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 260296704; Read time: 0; Write time: 251372 16:33:01 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293130240; Read time: 8; Write time: 206656 16:33:01 INFO - Running post-run listener: _upload_blobber_files 16:33:01 INFO - Blob upload gear active. 16:33:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:33:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:33:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:33:01 INFO - (blobuploader) - INFO - Open directory for files ... 16:33:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:33:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:33:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:02 INFO - (blobuploader) - INFO - Done attempting. 16:33:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:33:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:33:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:03 INFO - (blobuploader) - INFO - Done attempting. 16:33:03 INFO - (blobuploader) - INFO - Iteration through files over. 16:33:03 INFO - Return code: 0 16:33:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:33:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:33:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cf6e91d1228a3a9279fd3c1c0d5bd29a54fc629a6693d131d73c07debb078af1b4bb23f74f1788145a510c60aba9868eff7eaf223b7e2ef7a6700aff5ad02ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/968d530cd191d137a5b63571721a7c413746c2b6b0e5fb3776774ef45486954a902cf6ae81fd74e8f6e6dc458efc9456bc58daea1b3da21f13ba72727f8c4e80"} 16:33:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:33:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:33:03 INFO - Contents: 16:33:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cf6e91d1228a3a9279fd3c1c0d5bd29a54fc629a6693d131d73c07debb078af1b4bb23f74f1788145a510c60aba9868eff7eaf223b7e2ef7a6700aff5ad02ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/968d530cd191d137a5b63571721a7c413746c2b6b0e5fb3776774ef45486954a902cf6ae81fd74e8f6e6dc458efc9456bc58daea1b3da21f13ba72727f8c4e80"} 16:33:03 INFO - Running post-run listener: copy_logs_to_upload_dir 16:33:03 INFO - Copying logs to upload dir... 16:33:03 INFO - mkdir: /builds/slave/test/build/upload/logs 16:33:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.285634 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-05 16:33:03.419506) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 16:33:03.422832) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420 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/Firefox/sha512/9cf6e91d1228a3a9279fd3c1c0d5bd29a54fc629a6693d131d73c07debb078af1b4bb23f74f1788145a510c60aba9868eff7eaf223b7e2ef7a6700aff5ad02ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/968d530cd191d137a5b63571721a7c413746c2b6b0e5fb3776774ef45486954a902cf6ae81fd74e8f6e6dc458efc9456bc58daea1b3da21f13ba72727f8c4e80"} build_url:https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/WXWBIVhmQmesteWtfukT6A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cf6e91d1228a3a9279fd3c1c0d5bd29a54fc629a6693d131d73c07debb078af1b4bb23f74f1788145a510c60aba9868eff7eaf223b7e2ef7a6700aff5ad02ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/968d530cd191d137a5b63571721a7c413746c2b6b0e5fb3776774ef45486954a902cf6ae81fd74e8f6e6dc458efc9456bc58daea1b3da21f13ba72727f8c4e80"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 16:33:03.446904) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:33:03.447183) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1462489508.300299-390482420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 16:33:03.528996) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 16:33:03.529299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 16:33:03.529587) ========= ========= Total master_lag: 0.16 =========