builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-021 starttime: 1464224478.32 results: success (0) buildid: 20160525152143 builduid: 8654ccbbea454f27b451ee4808dca30e revision: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.323977) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.324403) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.324692) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.373168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.373476) ========= 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=df17445c3590ff7897617a0e00000225-1464222255.740603-218036450 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-25 18:01:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2016-05-25 18:01:18 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108788 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.506764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.507099) ========= 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=df17445c3590ff7897617a0e00000225-1464222255.740603-218036450 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014454 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.561365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.561710) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 --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=df17445c3590ff7897617a0e00000225-1464222255.740603-218036450 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-25 18:01:18,607 truncating revision to first 12 chars 2016-05-25 18:01:18,607 Setting DEBUG logging. 2016-05-25 18:01:18,607 attempt 1/10 2016-05-25 18:01:18,607 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4f7c3a2567f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 18:01:18,910 unpacking tar archive at: mozilla-aurora-4f7c3a2567f8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664792 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.261254) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.264682) ========= script_repo_revision: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.265168) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.265451) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.275286) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-25 18:01:19.275704) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=df17445c3590ff7897617a0e00000225-1464222255.740603-218036450 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:01:19 INFO - MultiFileLogger online at 20160525 18:01:19 in /builds/slave/test 18:01:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:01:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:19 INFO - {'append_to_log': False, 18:01:19 INFO - 'base_work_dir': '/builds/slave/test', 18:01:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:01:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:19 INFO - 'branch': 'Mozilla-Aurora', 18:01:19 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:19 INFO - 'config_files': ('talos/linux_config.py',), 18:01:19 INFO - 'default_actions': ('clobber', 18:01:19 INFO - 'read-buildbot-config', 18:01:19 INFO - 'download-and-extract', 18:01:19 INFO - 'populate-webroot', 18:01:19 INFO - 'create-virtualenv', 18:01:19 INFO - 'install', 18:01:19 INFO - 'run-tests'), 18:01:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:19 INFO - 'download_minidump_stackwalk': True, 18:01:19 INFO - 'download_symbols': 'ondemand', 18:01:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:19 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:19 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:19 INFO - 'installer_path': 'installer.exe', 18:01:19 INFO - 'log_level': 'info', 18:01:19 INFO - 'log_name': 'talos', 18:01:19 INFO - 'log_to_console': True, 18:01:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:01:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:01:19 INFO - 'opt_config_files': (), 18:01:19 INFO - 'pip_index': False, 18:01:19 INFO - 'sps_profile': False, 18:01:19 INFO - 'sps_profile_interval': 0, 18:01:19 INFO - 'suite': 'dromaeojs-e10s', 18:01:19 INFO - 'system_bits': '32', 18:01:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:19 INFO - 'title': 'talos-linux64-ix-021', 18:01:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:19 INFO - 'use_talos_json': True, 18:01:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:19 INFO - 'work_dir': 'build'} 18:01:19 INFO - ##### 18:01:19 INFO - ##### Running clobber step. 18:01:19 INFO - ##### 18:01:19 INFO - Running pre-action listener: _resource_record_pre_action 18:01:19 INFO - Running main action method: clobber 18:01:19 INFO - rmtree: /builds/slave/test/build 18:01:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:20 INFO - Running post-action listener: _resource_record_post_action 18:01:20 INFO - ##### 18:01:20 INFO - ##### Running read-buildbot-config step. 18:01:20 INFO - ##### 18:01:20 INFO - Running pre-action listener: _resource_record_pre_action 18:01:20 INFO - Running main action method: read_buildbot_config 18:01:20 INFO - Using buildbot properties: 18:01:20 INFO - { 18:01:20 INFO - "project": "", 18:01:20 INFO - "product": "firefox", 18:01:20 INFO - "script_repo_revision": "production", 18:01:20 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 18:01:20 INFO - "repository": "", 18:01:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 18:01:20 INFO - "buildid": "20160525152143", 18:01:20 INFO - "pgo_build": "True", 18:01:20 INFO - "basedir": "/builds/slave/test", 18:01:20 INFO - "buildnumber": 290, 18:01:20 INFO - "slavename": "talos-linux64-ix-021", 18:01:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:01:20 INFO - "platform": "ubuntu64_hw", 18:01:20 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:01:20 INFO - "branch": "mozilla-aurora", 18:01:20 INFO - "repo_path": "releases/mozilla-aurora", 18:01:20 INFO - "revision": "4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3", 18:01:20 INFO - "stage_platform": "linux64-pgo", 18:01:20 INFO - "builduid": "8654ccbbea454f27b451ee4808dca30e", 18:01:20 INFO - "slavebuilddir": "test" 18:01:20 INFO - } 18:01:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 18:01:20 INFO - Running post-action listener: _resource_record_post_action 18:01:20 INFO - ##### 18:01:20 INFO - ##### Running download-and-extract step. 18:01:20 INFO - ##### 18:01:20 INFO - Running pre-action listener: _resource_record_pre_action 18:01:20 INFO - Running main action method: download_and_extract 18:01:20 INFO - mkdir: /builds/slave/test/build/tests 18:01:20 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')]} 18:01:20 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:01:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:01:21 INFO - Downloaded 1361 bytes. 18:01:21 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:21 INFO - Using the following test package requirements: 18:01:21 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:01:21 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:01:21 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:01:21 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'jsshell-linux-x86_64.zip'], 18:01:21 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:01:21 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:01:21 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:01:21 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:01:21 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:01:21 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:21 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:01:21 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:21 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:01:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:01:22 INFO - Downloaded 24135950 bytes. 18:01:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:01:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:01:24 INFO - Return code: 0 18:01:24 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:01:24 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')]} 18:01:24 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:01:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:01:28 INFO - Downloaded 24135950 bytes. 18:01:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:01:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:01:29 INFO - Return code: 0 18:01:29 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')]} 18:01:29 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:01:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:01:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:01:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:01:31 INFO - Downloaded 10840145 bytes. 18:01:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:01:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:01:31 INFO - Return code: 0 18:01:31 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')]} 18:01:31 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:01:32 INFO - Downloaded 62768089 bytes. 18:01:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:32 INFO - mkdir: /builds/slave/test/properties 18:01:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:32 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:32 INFO - Contents: 18:01:32 INFO - build_url:https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:33 INFO - Running post-action listener: _resource_record_post_action 18:01:33 INFO - Running post-action listener: set_extra_try_arguments 18:01:33 INFO - ##### 18:01:33 INFO - ##### Running populate-webroot step. 18:01:33 INFO - ##### 18:01:33 INFO - Running pre-action listener: _resource_record_pre_action 18:01:33 INFO - Running main action method: populate_webroot 18:01:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:33 INFO - '--tppagecycles', 18:01:33 INFO - '7'], 18:01:33 INFO - 'tests': ['tsvgm']}}, 18:01:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['damp', 'tps']}, 18:01:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'tests': ['damp', 'tps']}, 18:01:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['dromaeo_dom']}, 18:01:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['a11yr', 18:01:33 INFO - 'ts_paint', 18:01:33 INFO - 'tpaint', 18:01:33 INFO - 'sessionrestore', 18:01:33 INFO - 'sessionrestore_no_auto_restore', 18:01:33 INFO - 'tabpaint']}, 18:01:33 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:33 INFO - 'ts_paint', 18:01:33 INFO - 'tpaint', 18:01:33 INFO - 'sessionrestore', 18:01:33 INFO - 'sessionrestore_no_auto_restore', 18:01:33 INFO - 'tabpaint']}, 18:01:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:33 INFO - 'ts_paint', 18:01:33 INFO - 'tpaint', 18:01:33 INFO - 'sessionrestore', 18:01:33 INFO - 'sessionrestore_no_auto_restore', 18:01:33 INFO - 'tabpaint']}, 18:01:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:33 INFO - 'ts_paint', 18:01:33 INFO - 'tpaint', 18:01:33 INFO - 'sessionrestore', 18:01:33 INFO - 'sessionrestore_no_auto_restore', 18:01:33 INFO - 'tabpaint']}, 18:01:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['a11yr', 18:01:33 INFO - 'ts_paint', 18:01:33 INFO - 'tpaint', 18:01:33 INFO - 'sessionrestore', 18:01:33 INFO - 'sessionrestore_no_auto_restore', 18:01:33 INFO - 'tabpaint']}, 18:01:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['a11yr', 18:01:33 INFO - 'ts_paint', 18:01:33 INFO - 'tpaint', 18:01:33 INFO - 'sessionrestore', 18:01:33 INFO - 'sessionrestore_no_auto_restore', 18:01:33 INFO - 'tabpaint']}, 18:01:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['tsvgx', 18:01:33 INFO - 'tsvgr_opacity', 18:01:33 INFO - 'tart', 18:01:33 INFO - 'tscrollx', 18:01:33 INFO - 'cart']}, 18:01:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:33 INFO - 'tsvgr_opacity', 18:01:33 INFO - 'tart', 18:01:33 INFO - 'tscrollx', 18:01:33 INFO - 'cart']}, 18:01:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'talos_options': ['--disable-e10s'], 18:01:33 INFO - 'tests': ['tp5o']}, 18:01:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'tests': ['tp5o']}, 18:01:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'talos_options': ['--disable-e10s', 18:01:33 INFO - '--xperf_path', 18:01:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:33 INFO - 'tests': ['tp5n']}, 18:01:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:33 INFO - 'talos_options': ['--xperf_path', 18:01:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:33 INFO - 'tests': ['tp5n']}}, 18:01:33 INFO - 'talos.zip': {'path': '', 18:01:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:33 INFO - Running post-action listener: _resource_record_post_action 18:01:33 INFO - ##### 18:01:33 INFO - ##### Running create-virtualenv step. 18:01:33 INFO - ##### 18:01:33 INFO - Running pre-action listener: _resource_record_pre_action 18:01:33 INFO - Running main action method: create_virtualenv 18:01:33 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:33 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 18:01:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:33 INFO - Using real prefix '/usr' 18:01:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:34 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:36 INFO - Installing pip.................done. 18:01:36 INFO - Return code: 0 18:01:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01: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')]} 18:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:01:36 INFO - Using env: {'DISPLAY': ':0', 18:01:36 INFO - 'HOME': '/home/cltbld', 18:01:36 INFO - 'LANG': 'en_US.UTF-8', 18:01:36 INFO - 'LANGUAGE': 'en_US:en', 18:01:36 INFO - 'LOGNAME': 'cltbld', 18:01:36 INFO - 'MAIL': '/var/mail/cltbld', 18:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:36 INFO - 'MOZ_NO_REMOTE': '1', 18:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:36 INFO - 'NO_EM_RESTART': '1', 18:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:36 INFO - 'PWD': '/builds/slave/test', 18:01:36 INFO - 'SHELL': '/bin/bash', 18:01:36 INFO - 'SHLVL': '1', 18:01:36 INFO - 'TERM': 'linux', 18:01:36 INFO - 'TMOUT': '86400', 18:01:36 INFO - 'USER': 'cltbld', 18:01:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:36 INFO - '_': '/tools/buildbot/bin/python'} 18:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:36 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 18:01:36 INFO - Cleaning up... 18:01:36 INFO - Return code: 0 18:01:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01: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')]} 18:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:01:36 INFO - Using env: {'DISPLAY': ':0', 18:01:36 INFO - 'HOME': '/home/cltbld', 18:01:36 INFO - 'LANG': 'en_US.UTF-8', 18:01:36 INFO - 'LANGUAGE': 'en_US:en', 18:01:36 INFO - 'LOGNAME': 'cltbld', 18:01:36 INFO - 'MAIL': '/var/mail/cltbld', 18:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:36 INFO - 'MOZ_NO_REMOTE': '1', 18:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:36 INFO - 'NO_EM_RESTART': '1', 18:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:36 INFO - 'PWD': '/builds/slave/test', 18:01:36 INFO - 'SHELL': '/bin/bash', 18:01:36 INFO - 'SHLVL': '1', 18:01:36 INFO - 'TERM': 'linux', 18:01:36 INFO - 'TMOUT': '86400', 18:01:36 INFO - 'USER': 'cltbld', 18:01:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:36 INFO - '_': '/tools/buildbot/bin/python'} 18:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:36 INFO - Downloading/unpacking psutil>=0.7.1 18:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:38 INFO - Installing collected packages: psutil 18:01:38 INFO - Running setup.py install for psutil 18:01:38 INFO - building 'psutil._psutil_linux' extension 18:01:38 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 18:01:38 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 18:01:38 INFO - building 'psutil._psutil_posix' extension 18:01:38 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 18:01:39 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 18:01:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:39 INFO - Successfully installed psutil 18:01:39 INFO - Cleaning up... 18:01:40 INFO - Return code: 0 18:01:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:01:40 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')]} 18:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:40 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')]} 18:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:40 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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:40 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 18:01:40 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 18:01:40 INFO - Using env: {'DISPLAY': ':0', 18:01:40 INFO - 'HOME': '/home/cltbld', 18:01:40 INFO - 'LANG': 'en_US.UTF-8', 18:01:40 INFO - 'LANGUAGE': 'en_US:en', 18:01:40 INFO - 'LOGNAME': 'cltbld', 18:01:40 INFO - 'MAIL': '/var/mail/cltbld', 18:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:40 INFO - 'MOZ_NO_REMOTE': '1', 18:01:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:40 INFO - 'NO_EM_RESTART': '1', 18:01:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:40 INFO - 'PWD': '/builds/slave/test', 18:01:40 INFO - 'SHELL': '/bin/bash', 18:01:40 INFO - 'SHLVL': '1', 18:01:40 INFO - 'TERM': 'linux', 18:01:40 INFO - 'TMOUT': '86400', 18:01:40 INFO - 'USER': 'cltbld', 18:01:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:40 INFO - '_': '/tools/buildbot/bin/python'} 18:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:01:40 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 18:01:40 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 18:01:40 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 18:01:40 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 18:01:40 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 18:01:40 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 18:01:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:01:42 INFO - Installing collected packages: mozsystemmonitor 18:01:42 INFO - Running setup.py install for mozsystemmonitor 18:01:43 INFO - Successfully installed mozsystemmonitor 18:01:43 INFO - Cleaning up... 18:01:44 INFO - Return code: 0 18:01:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:01:44 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')]} 18:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:44 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')]} 18:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:44 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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:44 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 18:01:44 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 18:01:44 INFO - Using env: {'DISPLAY': ':0', 18:01:44 INFO - 'HOME': '/home/cltbld', 18:01:44 INFO - 'LANG': 'en_US.UTF-8', 18:01:44 INFO - 'LANGUAGE': 'en_US:en', 18:01:44 INFO - 'LOGNAME': 'cltbld', 18:01:44 INFO - 'MAIL': '/var/mail/cltbld', 18:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:44 INFO - 'MOZ_NO_REMOTE': '1', 18:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:44 INFO - 'NO_EM_RESTART': '1', 18:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:44 INFO - 'PWD': '/builds/slave/test', 18:01:44 INFO - 'SHELL': '/bin/bash', 18:01:44 INFO - 'SHLVL': '1', 18:01:44 INFO - 'TERM': 'linux', 18:01:44 INFO - 'TMOUT': '86400', 18:01:44 INFO - 'USER': 'cltbld', 18:01:44 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:44 INFO - '_': '/tools/buildbot/bin/python'} 18:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:46 INFO - Downloading/unpacking blobuploader==1.2.4 18:01:46 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 18:01:46 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 18:01:46 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 18:01:46 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 18:01:46 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 18:01:46 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 18:01:48 INFO - Downloading blobuploader-1.2.4.tar.gz 18:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:01:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:01:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 INFO - Downloading docopt-0.6.1.tar.gz 18:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:01:48 INFO - Installing collected packages: blobuploader, requests, docopt 18:01:48 INFO - Running setup.py install for blobuploader 18:01:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:01:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:01:49 INFO - Running setup.py install for requests 18:01:49 INFO - Running setup.py install for docopt 18:01:49 INFO - Successfully installed blobuploader requests docopt 18:01:49 INFO - Cleaning up... 18:01:49 INFO - Return code: 0 18:01:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01: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')]} 18:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:49 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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:49 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 18:01:49 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 18:01:49 INFO - Using env: {'DISPLAY': ':0', 18:01:49 INFO - 'HOME': '/home/cltbld', 18:01:49 INFO - 'LANG': 'en_US.UTF-8', 18:01:49 INFO - 'LANGUAGE': 'en_US:en', 18:01:49 INFO - 'LOGNAME': 'cltbld', 18:01:49 INFO - 'MAIL': '/var/mail/cltbld', 18:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:49 INFO - 'MOZ_NO_REMOTE': '1', 18:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:49 INFO - 'NO_EM_RESTART': '1', 18:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:49 INFO - 'PWD': '/builds/slave/test', 18:01:49 INFO - 'SHELL': '/bin/bash', 18:01:49 INFO - 'SHLVL': '1', 18:01:49 INFO - 'TERM': 'linux', 18:01:49 INFO - 'TMOUT': '86400', 18:01:49 INFO - 'USER': 'cltbld', 18:01:49 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:49 INFO - '_': '/tools/buildbot/bin/python'} 18:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:49 INFO - Running setup.py (path:/tmp/pip-YQiKZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:49 INFO - Running setup.py (path:/tmp/pip-efm6VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:49 INFO - Running setup.py (path:/tmp/pip-57c6_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:50 INFO - Running setup.py (path:/tmp/pip-AjyLx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:50 INFO - Running setup.py (path:/tmp/pip-cKT9nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:50 INFO - Running setup.py (path:/tmp/pip-j2BxMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:50 INFO - Running setup.py (path:/tmp/pip-syIrSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:50 INFO - Running setup.py (path:/tmp/pip-S4FZrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:50 INFO - Running setup.py (path:/tmp/pip-a7li6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:50 INFO - Running setup.py (path:/tmp/pip-OJCHq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:50 INFO - Running setup.py (path:/tmp/pip-IMt4to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:50 INFO - Running setup.py (path:/tmp/pip-TShn7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:50 INFO - Running setup.py (path:/tmp/pip-trSuWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:50 INFO - Running setup.py (path:/tmp/pip-257Flw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:50 INFO - Running setup.py (path:/tmp/pip-lf3JuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:50 INFO - Running setup.py (path:/tmp/pip-kCdhcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:50 INFO - Running setup.py (path:/tmp/pip-GJAbr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:01:51 INFO - Running setup.py install for manifestparser 18:01:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Running setup.py install for mozcrash 18:01:51 INFO - Running setup.py install for mozdebug 18:01:51 INFO - Running setup.py install for mozdevice 18:01:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Running setup.py install for mozfile 18:01:51 INFO - Running setup.py install for mozhttpd 18:01:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Running setup.py install for mozinfo 18:01:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Running setup.py install for mozInstall 18:01:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:01:51 INFO - Running setup.py install for mozleak 18:01:51 INFO - Running setup.py install for mozlog 18:01:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for moznetwork 18:01:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozprocess 18:01:52 INFO - Running setup.py install for mozprofile 18:01:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozrunner 18:01:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Running setup.py install for mozscreenshot 18:01:52 INFO - Running setup.py install for moztest 18:01:52 INFO - Running setup.py install for mozversion 18:01:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:01:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:01:52 INFO - Cleaning up... 18:01:52 INFO - Return code: 0 18:01:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01: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')]} 18:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01: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')]} 18:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:52 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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:52 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 18:01:52 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 18:01:52 INFO - Using env: {'DISPLAY': ':0', 18:01:52 INFO - 'HOME': '/home/cltbld', 18:01:52 INFO - 'LANG': 'en_US.UTF-8', 18:01:52 INFO - 'LANGUAGE': 'en_US:en', 18:01:52 INFO - 'LOGNAME': 'cltbld', 18:01:52 INFO - 'MAIL': '/var/mail/cltbld', 18:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:52 INFO - 'MOZ_NO_REMOTE': '1', 18:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:52 INFO - 'NO_EM_RESTART': '1', 18:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:52 INFO - 'PWD': '/builds/slave/test', 18:01:52 INFO - 'SHELL': '/bin/bash', 18:01:52 INFO - 'SHLVL': '1', 18:01:52 INFO - 'TERM': 'linux', 18:01:52 INFO - 'TMOUT': '86400', 18:01:52 INFO - 'USER': 'cltbld', 18:01:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:52 INFO - '_': '/tools/buildbot/bin/python'} 18:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:01:53 INFO - Running setup.py (path:/tmp/pip-Q_494O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:01:53 INFO - Running setup.py (path:/tmp/pip-aZS6Kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:01:53 INFO - Running setup.py (path:/tmp/pip-z_V9km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:01:53 INFO - Running setup.py (path:/tmp/pip-g7iumW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:01:53 INFO - Running setup.py (path:/tmp/pip-cKX8U7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:01:53 INFO - Running setup.py (path:/tmp/pip-hNriWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:01:53 INFO - Running setup.py (path:/tmp/pip-N0ICL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:01:53 INFO - Running setup.py (path:/tmp/pip-Mn04gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:01:53 INFO - Running setup.py (path:/tmp/pip-Ff6URO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:01:53 INFO - Running setup.py (path:/tmp/pip-AsBmXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:01:53 INFO - Running setup.py (path:/tmp/pip-T4spqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:01:53 INFO - Running setup.py (path:/tmp/pip-oM0jyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:01:53 INFO - Running setup.py (path:/tmp/pip-VYgrVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:01:53 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)) 18:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:01:53 INFO - Running setup.py (path:/tmp/pip-HlPhWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:01:54 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)) 18:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:54 INFO - Running setup.py (path:/tmp/pip-39JA1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:01:54 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)) 18:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:01:54 INFO - Running setup.py (path:/tmp/pip-Lzehpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:01:54 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)) 18:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:01:54 INFO - Running setup.py (path:/tmp/pip-bdei21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:01:54 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)) 18:01:54 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)) 18:01:54 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)) 18:01:54 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)) 18:01:54 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)) 18:01:54 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)) 18:01:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:01:54 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 18:01:54 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 18:01:54 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 18:01:54 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 18:01:54 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 18:01:54 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 18:01:56 INFO - Downloading blessings-1.6.tar.gz 18:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:01:56 INFO - Installing collected packages: blessings 18:01:56 INFO - Running setup.py install for blessings 18:01:56 INFO - Successfully installed blessings 18:01:56 INFO - Cleaning up... 18:01:56 INFO - Return code: 0 18:01:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:01:56 INFO - Reading from file tmpfile_stdout 18:01:56 INFO - Current package versions: 18:01:56 INFO - argparse == 1.2.1 18:01:56 INFO - blessings == 1.6 18:01:56 INFO - blobuploader == 1.2.4 18:01:56 INFO - docopt == 0.6.1 18:01:56 INFO - manifestparser == 1.1 18:01:56 INFO - mozInstall == 1.12 18:01:56 INFO - mozcrash == 0.17 18:01:56 INFO - mozdebug == 0.1 18:01:56 INFO - mozdevice == 0.48 18:01:56 INFO - mozfile == 1.2 18:01:56 INFO - mozhttpd == 0.7 18:01:56 INFO - mozinfo == 0.9 18:01:56 INFO - mozleak == 0.1 18:01:56 INFO - mozlog == 3.1 18:01:56 INFO - moznetwork == 0.27 18:01:56 INFO - mozprocess == 0.22 18:01:56 INFO - mozprofile == 0.28 18:01:56 INFO - mozrunner == 6.11 18:01:56 INFO - mozscreenshot == 0.1 18:01:56 INFO - mozsystemmonitor == 0.0 18:01:56 INFO - moztest == 0.7 18:01:56 INFO - mozversion == 1.4 18:01:56 INFO - psutil == 3.1.1 18:01:56 INFO - requests == 1.2.3 18:01:56 INFO - wsgiref == 0.1.2 18:01:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:56 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 18:01:56 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 18:01:56 INFO - Using env: {'DISPLAY': ':0', 18:01:56 INFO - 'HOME': '/home/cltbld', 18:01:56 INFO - 'LANG': 'en_US.UTF-8', 18:01:56 INFO - 'LANGUAGE': 'en_US:en', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MAIL': '/var/mail/cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SHLVL': '1', 18:01:56 INFO - 'TERM': 'linux', 18:01:56 INFO - 'TMOUT': '86400', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - '_': '/tools/buildbot/bin/python'} 18:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 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)) 18:01:56 INFO - Cleaning up... 18:01:56 INFO - Return code: 0 18:01:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:56 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 18:01:56 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 18:01:56 INFO - Using env: {'DISPLAY': ':0', 18:01:56 INFO - 'HOME': '/home/cltbld', 18:01:56 INFO - 'LANG': 'en_US.UTF-8', 18:01:56 INFO - 'LANGUAGE': 'en_US:en', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MAIL': '/var/mail/cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SHLVL': '1', 18:01:56 INFO - 'TERM': 'linux', 18:01:56 INFO - 'TMOUT': '86400', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - '_': '/tools/buildbot/bin/python'} 18:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:56 INFO - Downloading/unpacking jsonschema 18:01: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 18:01: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 18:01: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 18:01: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 18:01: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 18:01: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 18:01:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:01:58 INFO - Downloading/unpacking functools32 (from jsonschema) 18:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:59 INFO - Downloading functools32-3.2.3-2.tar.gz 18:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:01:59 INFO - warning: no files found matching '*.txt' 18:01:59 INFO - no previously-included directories found matching 'build' 18:01:59 INFO - no previously-included directories found matching 'dist' 18:01:59 INFO - no previously-included directories found matching '.git*' 18:01:59 INFO - Installing collected packages: jsonschema, functools32 18:01:59 INFO - Running setup.py install for functools32 18:01:59 INFO - warning: no files found matching '*.txt' 18:01:59 INFO - no previously-included directories found matching 'build' 18:01:59 INFO - no previously-included directories found matching 'dist' 18:01:59 INFO - no previously-included directories found matching '.git*' 18:01:59 INFO - Successfully installed jsonschema functools32 18:01:59 INFO - Cleaning up... 18:01:59 INFO - Return code: 0 18:01:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:01:59 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')]} 18:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:59 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')]} 18:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:59 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 0x7f63a7486f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, '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': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', '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 18:01:59 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 18:01:59 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 18:01:59 INFO - Using env: {'DISPLAY': ':0', 18:01:59 INFO - 'HOME': '/home/cltbld', 18:01:59 INFO - 'LANG': 'en_US.UTF-8', 18:01:59 INFO - 'LANGUAGE': 'en_US:en', 18:01:59 INFO - 'LOGNAME': 'cltbld', 18:01:59 INFO - 'MAIL': '/var/mail/cltbld', 18:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:59 INFO - 'MOZ_NO_REMOTE': '1', 18:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:59 INFO - 'NO_EM_RESTART': '1', 18:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:59 INFO - 'PWD': '/builds/slave/test', 18:01:59 INFO - 'SHELL': '/bin/bash', 18:01:59 INFO - 'SHLVL': '1', 18:01:59 INFO - 'TERM': 'linux', 18:01:59 INFO - 'TMOUT': '86400', 18:01:59 INFO - 'USER': 'cltbld', 18:01:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:59 INFO - '_': '/tools/buildbot/bin/python'} 18:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:59 INFO - Downloading/unpacking flake8 18:01:59 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 18:01:59 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 18:01:59 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 18:01:59 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 18:01:59 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 18:01:59 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 18:02:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:02:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:02:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:01 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 18:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:02 INFO - no previously-included directories found matching 'docs/_build' 18:02:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:02:02 INFO - Running setup.py install for pep8 18:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:02 INFO - no previously-included directories found matching 'docs/_build' 18:02:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:02:02 INFO - Cleaning up... 18:02:02 INFO - Return code: 0 18:02:02 INFO - Running post-action listener: _resource_record_post_action 18:02:02 INFO - Running post-action listener: _start_resource_monitoring 18:02:02 INFO - Starting resource monitoring. 18:02:02 INFO - ##### 18:02:02 INFO - ##### Running install step. 18:02:02 INFO - ##### 18:02:02 INFO - Running pre-action listener: _resource_record_pre_action 18:02:02 INFO - Running main action method: install 18:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:02 INFO - Reading from file tmpfile_stdout 18:02:02 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:02 INFO - Reading from file tmpfile_stdout 18:02:02 INFO - Output received: 18:02:02 INFO - Usage: mozinstall [options] installer 18:02:02 INFO - Options: 18:02:02 INFO - -h, --help show this help message and exit 18:02:02 INFO - -d DEST, --destination=DEST 18:02:02 INFO - Directory to install application into. [default: 18:02:02 INFO - "/builds/slave/test"] 18:02:02 INFO - --app=APP Application being installed. [default: firefox] 18:02:02 INFO - mkdir: /builds/slave/test/build/application 18:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:02:18 INFO - Reading from file tmpfile_stdout 18:02:18 INFO - Output received: 18:02:18 INFO - /builds/slave/test/build/application/firefox/firefox 18:02:18 INFO - Running post-action listener: _resource_record_post_action 18:02:18 INFO - ##### 18:02:18 INFO - ##### Running run-tests step. 18:02:18 INFO - ##### 18:02:18 INFO - Running pre-action listener: _resource_record_pre_action 18:02:18 INFO - Running main action method: run_tests 18:02:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:18 INFO - Python 2.7.3 18:02:18 INFO - Return code: 0 18:02:18 INFO - grabbing minidump binary from tooltool 18:02:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:18 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 0x1cfe410>, '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 0x1cff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cff7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:18 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 18:02:18 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 18:02:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:18 INFO - Return code: 0 18:02:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:02:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:02:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:02:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:02:19 INFO - Return code: 0 18:02:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 18:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 18:02:19 INFO - Using env: {'DISPLAY': ':0', 18:02:19 INFO - 'HOME': '/home/cltbld', 18:02:19 INFO - 'LANG': 'en_US.UTF-8', 18:02:19 INFO - 'LANGUAGE': 'en_US:en', 18:02:19 INFO - 'LOGNAME': 'cltbld', 18:02:19 INFO - 'MAIL': '/var/mail/cltbld', 18:02:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:19 INFO - 'MOZ_NO_REMOTE': '1', 18:02:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:19 INFO - 'NO_EM_RESTART': '1', 18:02:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:19 INFO - 'PWD': '/builds/slave/test', 18:02:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:19 INFO - 'SHELL': '/bin/bash', 18:02:19 INFO - 'SHLVL': '1', 18:02:19 INFO - 'TERM': 'linux', 18:02:19 INFO - 'TMOUT': '86400', 18:02:19 INFO - 'USER': 'cltbld', 18:02:19 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1464222255.740603-218036450', 18:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:19 INFO - '_': '/tools/buildbot/bin/python'} 18:02:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 18:02:19 INFO - mozversion application_buildid: 20160525152143 18:02:19 INFO - mozversion application_changeset: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 18:02:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:02:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:19 INFO - mozversion application_name: Firefox 18:02:19 INFO - mozversion application_remotingname: firefox-dev 18:02:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:02:19 INFO - mozversion application_vendor: Mozilla 18:02:19 INFO - mozversion application_version: 48.0a2 18:02:19 INFO - mozversion platform_buildid: 20160525152143 18:02:19 INFO - mozversion platform_changeset: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 18:02:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:02:19 INFO - mozversion platform_version: 48.0a2 18:02:19 INFO - using testdate: 1464224539 18:02:19 INFO - actual date: 1464224539 18:02:19 INFO - starting webserver on 'localhost:51290' 18:02:19 INFO - SUITE-START | Running 2 tests 18:02:19 INFO - TEST-START | dromaeo_css 18:02:19 INFO - operating with platform_type : linux_ 18:02:19 INFO - Initialising browser for dromaeo_css test... 18:02:19 INFO - TEST-INFO | started process 27073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZSj_4Q/profile http://localhost:51290/getInfo.html) 18:02:24 INFO - PROCESS | 27073 | 18:02:24 INFO - PROCESS | 27073 | (process:27151): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:02:25 INFO - PROCESS | 27073 | __metrics Screen width/height:1600/1200 18:02:25 INFO - PROCESS | 27073 | colorDepth:24 18:02:25 INFO - PROCESS | 27073 | Browser inner width/height: 1024/697 18:02:25 INFO - PROCESS | 27073 | __metrics 18:02:26 ERROR - PROCESS | 27073 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 18:02:27 INFO - TEST-INFO | 27073: exit 0 18:02:27 INFO - Browser initialized. 18:02:27 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:27 INFO - TEST-INFO | started process 27203 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZSj_4Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:28 INFO - PROCESS | 27203 | 18:02:28 INFO - PROCESS | 27203 | (process:27271): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:02:38 INFO - PROCESS | 27203 | RSS: Main: 152150016 18:02:38 INFO - PROCESS | 27203 | 18:03:28 INFO - PROCESS | 27203 | 1464224608536 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] 18:05:36 INFO - PROCESS | 27203 | Cycle 1(1): loaded http://localhost:51290/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51290/tests/dromaeo/cssquery-ext.html) 18:05:37 INFO - PROCESS | 27203 | RSS: Main: 155947008 18:05:37 INFO - PROCESS | 27203 | 18:08:38 INFO - PROCESS | 27203 | Cycle 1(1): loaded http://localhost:51290/tests/dromaeo/cssquery-ext.html (next: http://localhost:51290/tests/dromaeo/cssquery-jquery.html) 18:08:39 INFO - PROCESS | 27203 | RSS: Main: 153780224 18:08:39 INFO - PROCESS | 27203 | 18:11:41 INFO - PROCESS | 27203 | Cycle 1(1): loaded http://localhost:51290/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51290/tests/dromaeo/cssquery-mootools.html) 18:11:41 INFO - PROCESS | 27203 | RSS: Main: 153571328 18:11:41 INFO - PROCESS | 27203 | 18:14:43 INFO - PROCESS | 27203 | Cycle 1(1): loaded http://localhost:51290/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51290/tests/dromaeo/cssquery-prototype.html) 18:14:44 INFO - PROCESS | 27203 | RSS: Main: 153235456 18:14:44 INFO - PROCESS | 27203 | 18:17:46 INFO - PROCESS | 27203 | Cycle 1(1): loaded http://localhost:51290/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51290/tests/dromaeo/cssquery-yui.html) 18:17:47 INFO - PROCESS | 27203 | RSS: Main: 154288128 18:17:47 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | Cycle 1(1): loaded http://localhost:51290/tests/dromaeo/cssquery-yui.html (next: http://localhost:51290/tests/dromaeo/cssquery-dojo.html) 18:20:49 INFO - PROCESS | 27203 | RSS: Main: 156278784 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | __start_tp_report 18:20:49 INFO - PROCESS | 27203 | _x_x_mozilla_page_load 18:20:49 INFO - PROCESS | 27203 | _x_x_mozilla_page_load_details 18:20:49 INFO - PROCESS | 27203 | |i|pagename|runs| 18:20:49 INFO - PROCESS | 27203 | |0;dojo.html;3336.332733453309;3458.982705086475;3462.532558104657;3488.511608374826;3506.3863823830825;5707.885842283152;5714.8857022859565;5740.7129643517865;5751.338596061454;5754.827355179341;5712.628679135863;5731.340895796985;5732.742026608802;5744.138379243105;5756.942430575693;5148.047611191922;5190.143626301675;5194.454582268845;5254.31693879797;5260.842174734764;5042.672226305297;5157.458466860982;5163.741812909339;5315.920261196085;5323.574114070865;5122.078025955327;5181.041507321158;5206.869828254305;5235.790568377261;5237.8428647140645;5254.448282930275;5256.9737151314;5262.973685131588;5266.815661451831;5318.361796584396;5036.798528058874;5052.974735126338;5109.3613298337705;5158.561522270612;5225.320708307933;5546.639468434539;5586.636868603528;5595.664260144405;5600.635958662674;5669.177969194444;6051.667158306292;6057.545684073712;6070.392960703938;6096.359882212349;6101.359357267467;5970.492508136814;6047.818565443;6152.077188421727;6152.169457123276;6170.228721409824;5626.2967129108865;5644.294463192101;5651.378348381675;5653.886922261573;5680.5455563554815;6141.662208578482;6163.414475624809;6164.907526387108;6165.445109940127;6165.537584681167;5847.181394604759;5848.122781582797;5893.5579831512805;5903;5914.704264786829;5760.2223699800825;5764.452377024176;5783.7397317119985;5787.450192231731;5854.824355269348;5687.971560142173;5704.857378565569;5744.080947048452;5750.281214848144;5805.651660900359;5819.88360232793;5875.6474611523445;5877.706114694248;5965.0455927052335;5967.1347654589845;4533.297338912408;4556.908861822745;4557.863264102082;4560.17916774977;4569.200389931749;4078.57174996627;4093.7953102344763;4093.815778289984;4107.630313271819;4124.711270211116;5764.193012978187;5925.970370148208;5955.047192449188;5962.433568810956;6004.159417681441;6012.158297838306;6014.669193194329;6058.0610005449225;6075.270967483842;6086.147939288503;5736.053551164095;5825.300963884361;5830.912536311958;5831.504322132581;5846.473817356458;5262.894742105136;5324.627276090714;5329.280547126166;5350.5184533392185;5361.544268737201;6263.498920086382;6283;6296.590721603081;6303.2751233607805;6334.3032266450655;5949.91075133882;6083.270007599116;6102.816915492543;6115.266168059861;6128.26460824695;6223.564350495421;6284.497240220863;6288.27684816252;6289.40250676185;6292.11910332563;5949.881002379841;5950.077737950624;5957.076653118774;5967.224260846062;6041.667708276087;6011.519078473624;6071.483923866521;6096.207493025879;6102.4507794298725;6150.600210986271;5893.263342082239;5900.734466948912;5901.3213480448585;5901.380355062656;5922.289325280994;5867.911981320198;5871.4715675589405;5874.118882167709;5877.529797616266;5886.117082437669;5734.684592347294;5743.253377060955;5861.062230043172;5906.26171728534;5960.761569537343;6006.549508786771;6018.608790428608;6024.096385542204;6029.397060293935;6050.2437195350585;5893.086571581412;5896.9115463268945;5915.349311575809;5924.822255332347;5929.140274660236 18:20:49 INFO - PROCESS | 27203 | |1;ext.html;597.5727354941218;636.503527248746;653.6764301670673;658.246307977366;660.1286302081248;33698.15754606136;33805;33836.83081584595;34034;34068.65931340686;36485.817570912106;36506.81746591263;36914.81542592283;36995.26009479809;37147;8483.406161568693;8746.125387461267;8748.650053997864;8754.912450875474;8762.824743505125;8568.871466928002;8656;8688.826223475528;8843.11568843117;8864.202221800037;8861.069587693297;8951.328650351217;8960.64157433705;8961.327900407496;8975.910240897605;8774.341924355698;8811.206991370744;8843.248323892445;8884.644614215424;8887.24458421035;8763.342749293828;8817.29461643067;8877.023527411979;8881.689140880038;8884.822303553958;36343.09142271438;36391.81804090989;36643.4503482448;36663;36792.26415471702;3424.5719285089363;3537.9292414151573;3555.520004799345;3565.447355659877;3585.0678823505814;28533.57199642007;28994.985175518756;29061.85469072662;29396;29519.409611807645;4454.554544545552;4538.296564032547;4584.633229341644;4606.055758569519;4646.186917289495;292356;298942;298965;299470.99999999785;299861;56542.717286414125;56955.71522142363;57025.71487142538;57075;57111.714441427524;55078.72460637671;55653;55920.720396398574;55925.7203713987;56098.158527622116;14293.428262869369;14379.712405751827;14396.712065758835;14409.78385324221;14416.351264193136;11115;11166.776664466664;11168.944155279172;11179.385133817555;11207.551697931991;18656.626867462575;18834.246630134916;18932;18952.810471895104;19642.214311427382;6103.877922441527;6235.282942461584;6249.500039996789;6256.718447669775;6278.968605156945;954.1269738189479;1023;1050.8020856223911;1099.340395762536;1144.210494758614;28133.296667583476;28829.279268018046;28957.855210724232;29104.708952910623;29535.113946581638;1079.7246702091074;1083.21466936472;1087.8422628718793;1123.5112725964232;1140.2417392433938;14967.925160374347;15135.167565783768;15153.696926061417;15211.391544338323;15406.460773873085;493721;831879;839079;845142;848633;50539.49460505421;51176;51222;51293.487065129615;51424.22863657048;1331.261150061725;1334.5129027904939;1335.1922087137336;1340.0552610409638;1394.6443656867636;28848.278793030342;29058.7094129056;29279.56080658749;29417.705822942353;29771.702282976894;42756.358654620824;43171.13657726891;43203;43283.78358108315;43542.56457435512;309886;310516;310896;313093;313551;26873.193804185907;27004.72995270022;27307.453850923266;27731.44537109287;27779.722202777713;41249;41555.37666935059;41734.582654174286;41972.79013604912;42848.35727464028;35203.64796352004;35749.46375804417;35757.46363804493;35947;36236.27527449383;35077.12307192341;35404.822975884956;35448.64551354557;35599.754008609576;35876.461853072746;31549.526757098203;37126.25747485089;37508.624913751606;37778.62221377861;38099.42850857184;29444.852775735984;30206;30495.695043050175;30691.539626905167;30761;24542.83829965848;24748.87625561861;25392.619110712985;25435.36411589725;25471.10851120202 18:20:49 INFO - PROCESS | 27203 | |2;jquery.html;394.4792873407103;397.3324814311957;401.74489199358436;404.58327922240096;407.88783084651715;14389.856101438982;16500.504984850475;17954.281828726853;18628.72056919144;18654.53363665909;15714.292856821441;15820;15936.203189840491;16652.250648720805;16691.83308166918;5455.936092461971;5658.858528536778;5663.886722265553;5667.631603945731;5670.716464176795;5366.570674346062;5652.943470565303;5657.179708942201;5657.745401456944;5666.660000399968;5592.748326325325;5606.747696353674;5608.915866262009;5658.151277308416;5675.744591493372;5503.477169668895;5596.944030559704;5683.2895888014;5697.31632204134;5698.17376480408;5113.079645663779;5117.411497677777;5136.794528218867;5143.099957507421;5147.510986456318;21147.365579032652;21443.249486267905;21968.670469942965;21971.450713732123;22038.228661996753;2258.4241018540165;2359.28041947206;2417.2385698504913;2533.695956485215;2713.7150599187;9933.304668673196;10539.473026348653;10892.509837057352;10950.780984380348;13567.389467473988;2009.5076706206926;2011.1754180785808;2055.650539408289;2101.7057611934342;2114.74623045234;76027.23972760313;76319.61840190763;76565.61717191435;78285.60857195735;79335;10707.518161682743;10816.242862999672;10886.836697449544;11136.33182009082;11208.439578021229;14923.850761492246;15032.398704051715;15066.171360575274;15099.924500377647;15501.224938753243;6538.509611779041;6565.934340656628;7558.093028836574;7897.842043159219;7967.482113662593;7345.485815992936;7426.368758655467;7443.441741869381;7464.290892365217;7557.093148822177;4621.699589526737;4707.164205959679;4759.809607615726;4762.309465127537;5041.747912604355;1924.143756028582;1980.9504762380768;1984.3550845975117;1988.7414636097503;2007.6185524750251;941.3786900645541;949.3734135470556;984.5667906121283;1011.3881101933372;1049.338916482611;17799.377021804175;17907;17927.820721792876;18399.908000459913;18617.255309787728;948.8624149498285;993.8608594796734;998.1216529454034;1009.3590569988111;1026.2149455666422;10304.969503049784;10407.271490995709;10490.790184196427;10545;10565.683029509128;435818;438060;441616;473181.315961819;500302;89218.55390723134;91991.54004229936;92192;92834.53582732043;92988;945.3618807305179;973.2797729679986;973.6884197056874;973.72735634024;981.489625394805;16057.437989670305;16285.430009950069;16292.837071629607;16395.262213200196;16457.341706332092;37143.6999705009;37749.81125094357;38805.80597096997;38852.41721374238;38925.805370972965;55656.721716391155;56062.439375605725;56068.71965640146;56084;56136.43863561312;9091.409058411182;9151.581504866755;9190.21883139914;9334.25325973907;9343.29925255595;12429.502819887104;13025.153365031243;13136.868631313946;13256;13276.933615331862;10948.397838118979;11139.944300278446;11141.2201145919;11256.437178140897;11357.886421135683;6379.872402552016;9199.666048423074;10391.220658450495;10568.89431105679;10983.176261780558;11112.166587506132;11268;11336.716582085513;11518.53925842957;11597.07223422141;8948.5078320693;8951.060138685345;9089.909100908906;9105.226055785333;9216.21662158724;10269.845952310572;11193.608223712132;11262.718432038937;11320.716982075513;11357.54569817198 18:20:49 INFO - PROCESS | 27203 | |3;mootools.html;1284.5504073574248;1307.9019073569484;1326.396489597233;1508.3514088941765;1522.687848990957;7448.478606497547;7789.688412463494;7887.408444366674;7909.762707118782;8071.556064416454;7327.413806895449;7961.6019199039965;8085.151059138794;8155.877661835078;8252.752417427488;2368.917087901928;2411.8311718179734;2412.1316326122583;2420.576399130145;2425.2845410603913;2398.760123987605;2400.6879105716234;2419.697537807774;2422.878856057199;2426.0538390027905;2353.5528249632603;2368.6328619064075;2372.252740386781;2373.3117395955237;2380.95238095238;2382.0709923129907;2387.462820865309;2388.3312672451566;2401.5677178107935;2417.2627348658652;2367.5501654685554;2368.6920700309024;2370.5969985102574;2376.192094687797;2382.7140743110763;8442.366822488339;8517.872231916526;8519.40364174508;8526.701565445179;8542.487450752975;5114.7698353573805;5127.077126117298;5201.635885488017;5229.5293423591675;5248.685078895241;3419.9658003419895;3452.9309413811834;3502.2820321833965;3516.9120771980906;3570.250247448039;1140.880207578209;1171.859376874772;1181.285322379965;1184.8933595976318;1204.265398107154;12042.81935770964;12273.754524909542;12279.815802762876;12304.446299916524;12304.876951230552;4686.695364801277;4726.905461890743;4730.077634861169;4765.618750499952;4809.134355815987;10431.37411755282;10443.582256709646;10493.108085812639;10508.737281568023;10514.684559463076;5221.112410890158;5278.788848446096;5290.81482148123;5296.655717378358;5304.204369344629;4123.8556650517685;4547.545245475426;4639.907201855944;4644.349791029259;4815.2054910940005;3794.4118661607495;3834.731568790158;3850.441685955521;3871.651551360335;3898.181381909774;1318.736252749454;1331.9067665263533;1333.2333908002938;1350.1021820489461;1350.540816122523;882.5895958379384;884.9646014159362;885;889.213046453893;912.6577533424966;5510.586705997066;5603.467670571289;5643.351014633287;5646.661200327993;5650.830475085671;976.6923419122953;996.051687006469;996.3971797062818;1010.2673914237118;1039.417925961464;3668.9633103669157;3793.981030094832;3795.2599243147874;3800.3349413852466;3818.3317919364554;10131.08820206185;10571.207159462918;10661.093807026335;10664.306820056672;10722.892771072346;10473.84289235662;10484.790304193873;10490.947545262225;10505.9474702626;10547.947260263649;7999.4800337976685;7999.840003199904;8003.4797738146835;8092.757217283491;8109.270165685117;5523.613347065828;5526.336839579116;5577.191307260344;5629.26819513477;5670;9326.160645541933;9507;9561.139497445263;9655.855162172433;9663.516824158905;9536.952315238657;9651.517424128626;9770.120689138012;9799;10009.899901000897;6961.477889158232;7281.635918204174;7480.2145774695055;7767.1067827200595;7870;8161.959190204011;8205.79485512867;8208.794780130545;8233.259006689426;8275.83448331042;7110.25342339047;7136.179339376038;7149.320814522612;7311.159216690149;7433.851322973402;7106.857862842611;7166.856662866818;7243.275672432715;7272.709091636487;7386.335229829341;6593.538452308292;6596.406323430722;6626.337366263335;6636.336366363325;6678.031685405686;4787.138315103315;4902.94586663869;4933.260010998379;4947.3073769671555;4989.301497790385;4861.343718597944;4898.461169271306;4901.09329773993;4905.092557876662;4931.408231012158 18:20:49 INFO - PROCESS | 27203 | |4;prototype.html;355.9341521818463;357.545916685809;361.6979821848756;362.9600743918169;367.3717942318635;6582.111414958979;6603.141591593087;6649.767258145966;6667.566608170471;6667.666616669171;6585.473162147029;6590.373914478129;6596.340365963413;6604.834879128012;6612.206535215781;4670.602998745102;4862.805487780484;5037.06814239365;5049.318342023835;5061.468545802693;4996.850094497171;5043.193089105744;5045.041442126003;5046.697198168104;5047.066292735837;4994.375703037121;5004.872929005176;5009.198528235483;5009.474005229453;5014.2979982802435;4998.375203099613;5016.347874776292;5017.297578339036;5017.49825017499;5019.623528235397;4415.381846541487;4416.028473735773;4417.580329868657;4421.60205581496;4425.291953287458;6086.2087928569;6331.55679102463;6360.427561519486;6378.968105159491;6420.839479013015;5781.53747700183;5807.941920580783;5811.912821307642;5814.97092514539;5815.534757219412;7014.403775679073;7014.92985070153;7019.157701075853;7020.719171233144;7028.051213086219;4789.544993225672;4802.879928001793;4802.927956080626;4817.108834865526;4817.807287708488;7163.820904477378;7282.672279747424;7301.817454563678;7329.377002954702;7362.190159082496;4875.317455556225;4911.287863259808;4914.238293064581;4923.556879880828;4928.507149285043;4944.332515110486;4976.950230497721;4979.402471703419;4991.675541089818;4995.9500404996215;5787.392323806024;5788.334341550775;5789.536837053026;5791.305043394819;5803.332616749128;7077.433805295667;7081.645917704094;7085.610291433917;7088.681009354591;7103.573785572882;1732.3157352845808;1759.7888253409417;1768.1689605885213;1771.5748220427004;1772.9645407092044;1126.4818183635455;1147.632757517603;1149.172595731089;1153.4751687981948;1154.440096553171;6821.727130914807;6842.178938527309;6847.281035491202;6849.48628852838;6852.588844669236;7158.463115266376;7246.166690830517;7306.9404936283945;7321;7371.778846634609;6870.519063665495;6878.312168783082;6880.58716477013;6886.2769409212315;6897.172339319213;3943.901402464904;3981.8009099544906;3983.1635356575443;3989.3218152913496;3993.201359728066;7614.58119803405;7633.122190948001;7640.465167438337;7642.19756925526;7657.731979380695;7294.671739771723;7524.322810947041;7536.0956685198125;7547.7735667928955;7582.090149182133;7632.236776322435;7639.69441222356;7646.579438130845;7652.273034061755;7663.655135518915;7255.673494692646;7332.743353982585;7333.339999399866;7344.816379590553;7344.8898266524975;7163.534370265916;7188.137423509213;7224.711011559479;7234.74678386254;7263.164736055422;7305.634718263959;7557.05536807899;7635.160132385544;7653.540787552764;7693.692252309845;4862.173430516891;4910.21436570147;4939.827106051271;4949.257611358325;4952.380952380952;7303.233160518069;7327.377172940147;7332.193458719643;7337.6698048589005;7339.082614673166;7116.928830711627;7197.568145911262;7203.531770434905;7207.675654595451;7212.963935180291;6968.79093627192;7062.646867656699;7068.68190931399;7077.256888026889;7085.504014719127;6937.375636192767;6940.9652951734915;6942.375186233263;6953.6523173842115;6973.163220413583;7122.074130363019;7126.679299431435;7127.216006239413;7133.464990125865;7136.85726285461;6788.864222715617;6867.931320686729;6909.827254318682;6929.68816403253;6935.479839011993 18:20:49 INFO - PROCESS | 27203 | |5;yui.html;699.1226011355748;734.177720952533;736.9705211791533;757.5606148433909;770.8728059870122;14467.855321446783;14869.405223791062;14899.702005959874;14975.251237438126;15020.549383518512;13265.734685306288;13267.469301227964;13307.534236301732;13336.466541338335;14320.212388318665;593.7535922592119;601.0653433910268;608.4888693497484;609.5002098279413;613.9447449729523;610.0239616613427;610.7434877351501;611.4344231585787;611.440531913301;620.4478014567017;651.6904470376556;651.8272657745702;652.725855140842;655.8950567909136;673.6497021548792;646.0954663471131;646.1083704487777;647.7085311609748;648.8604949935763;652.5236577298575;479.6306843730343;479.81287297953827;480.3083559674058;481.6508031677041;483.6469377354551;13411.865881341158;13538.323083845868;13541.05212635116;13567.59297221085;13608.591742247749;13110.213387196798;13168.144070635379;13336.399862006234;13451.855101654139;13573.86426135736;11846;11909.225900316538;12162.878371216351;12363.876361236453;12456.688582785413;3822.4075268333454;3851.9807400963095;3869.535655721303;3880.825362858678;3941.310270702644;57185.42814571884;57320.713396433166;57347.42652573463;57446.71276643632;57711.71144144337;10313.329633573794;10729.83905241422;10935.781284374427;10980.121590272758;11025.062869656009;16641.750373744162;16760.329586816635;16807.327706891832;16827.074510901773;16842.915785420995;8186.959065204635;8198.987101763863;8274.586270686563;8289.0467596226;8437.915620843836;4797.088553174885;4808.927866082012;4849.781759820817;4890.535399137077;4991.875203119951;3203.3593281343824;3233.579634647481;3233.935321293561;3259.3481303739345;3269.3297873936262;616.3282022595303;616.9105479705418;618.6566455617086;620.5283984172062;626.0859145647315;692.3007762160154;743.3569961982954;761.493606751504;778.5990215039259;781.6443518199319;9301.860472092923;9666.420014799134;9877.259205559612;9879.861128456292;9953.452560109119;779.5829231361195;790.1032328307413;816.1675091406731;818.9590520473953;824.6083110522454;4480.529544397857;4561.68068235227;4585.656075794329;4624.167649822997;4700.271457924027;57708.71145644245;58185.70907145522;58380;58384.124238136465;59160;51208.48791512111;52467.73766131145;52637.47362526402;53209.733951329996;54577;46488.767556162005;46882.765586172536;47375.76312118417;48444.273335900696;48656.7567162169;8799.604017819085;9120.589573469077;9185.908140918773;9235;9272.860907086533;34778.652213477544;35796.105097372776;37007.44488832616;37079.25841483209;37199.81400092982;44006.339904900815;44247.33628995632;47279.29081063718;48351.75824120857;49115.75442122766;10619.840702389316;10682.145428365868;10762.569497220024;10866.94566527194;10904.94547527289;12670.366481676063;12978.935105324414;13039.087263891437;13151.21092734439;13845.584632461043;11503.884961150281;11633.709157271136;11686.474108665301;11779.293242405483;12126.696832579257;12059.21615095016;12251.816222756488;12284.1401101922;12371;12543.811842822548;10949.343039417661;11234.213605047567;11311.151663625425;11356.375399353115;11364.602238921598;9970.152537034433;9997.200223982209;10431;10472.581096756348;10834;9417;10002.649907253211;10238.590456381662;11161.162912781412;11197.944010279896 18:20:49 INFO - PROCESS | 27203 | __end_tp_report 18:20:49 INFO - PROCESS | 27203 | __start_cc_report 18:20:49 INFO - PROCESS | 27203 | _x_x_mozilla_cycle_collect,344 18:20:49 INFO - PROCESS | 27203 | __end_cc_report 18:20:49 INFO - PROCESS | 27203 | __startTimestamp1464225649380__endTimestamp 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | ------- Summary: start ------- 18:20:49 INFO - PROCESS | 27203 | Number of tests: 6 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | [#0] dojo.html Cycles:165 Average:5631.63 Median:5846.47 stddev:613.37 (10.5%) stddev-sans-first:588.23 18:20:49 INFO - PROCESS | 27203 | Values: 3336.3 3459.0 3462.5 3488.5 3506.4 5707.9 5714.9 5740.7 5751.3 5754.8 5712.6 5731.3 5732.7 5744.1 5756.9 5148.0 5190.1 5194.5 5254.3 5260.8 5042.7 5157.5 5163.7 5315.9 5323.6 5122.1 5181.0 5206.9 5235.8 5237.8 5254.4 5257.0 5263.0 5266.8 5318.4 5036.8 5053.0 5109.4 5158.6 5225.3 5546.6 5586.6 5595.7 5600.6 5669.2 6051.7 6057.5 6070.4 6096.4 6101.4 5970.5 6047.8 6152.1 6152.2 6170.2 5626.3 5644.3 5651.4 5653.9 5680.5 6141.7 6163.4 6164.9 6165.4 6165.5 5847.2 5848.1 5893.6 5903.0 5914.7 5760.2 5764.5 5783.7 5787.5 5854.8 5688.0 5704.9 5744.1 5750.3 5805.7 5819.9 5875.6 5877.7 5965.0 5967.1 4533.3 4556.9 4557.9 4560.2 4569.2 4078.6 4093.8 4093.8 4107.6 4124.7 5764.2 5926.0 5955.0 5962.4 6004.2 6012.2 6014.7 6058.1 6075.3 6086.1 5736.1 5825.3 5830.9 5831.5 5846.5 5262.9 5324.6 5329.3 5350.5 5361.5 6263.5 6283.0 6296.6 6303.3 6334.3 5949.9 6083.3 6102.8 6115.3 6128.3 6223.6 6284.5 6288.3 6289.4 6292.1 5949.9 5950.1 5957.1 5967.2 6041.7 6011.5 6071.5 6096.2 6102.5 6150.6 5893.3 5900.7 5901.3 5901.4 5922.3 5867.9 5871.5 5874.1 5877.5 5886.1 5734.7 5743.3 5861.1 5906.3 5960.8 6006.5 6018.6 6024.1 6029.4 6050.2 5893.1 5896.9 5915.3 5924.8 5929.1 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | [#1] ext.html Cycles:180 Average:59369.98 Median:19671.27 stddev:139865.96 (711.0%) stddev-sans-first:140188.71 18:20:49 INFO - PROCESS | 27203 | Values: 597.6 636.5 653.7 658.2 660.1 33698.2 33805.0 33836.8 34034.0 34068.7 36485.8 36506.8 36914.8 36995.3 37147.0 8483.4 8746.1 8748.7 8754.9 8762.8 8568.9 8656.0 8688.8 8843.1 8864.2 8861.1 8951.3 8960.6 8961.3 8975.9 8774.3 8811.2 8843.2 8884.6 8887.2 8763.3 8817.3 8877.0 8881.7 8884.8 36343.1 36391.8 36643.5 36663.0 36792.3 3424.6 3537.9 3555.5 3565.4 3585.1 28533.6 28995.0 29061.9 29396.0 29519.4 4454.6 4538.3 4584.6 4606.1 4646.2 292356.0 298942.0 298965.0 299471.0 299861.0 56542.7 56955.7 57025.7 57075.0 57111.7 55078.7 55653.0 55920.7 55925.7 56098.2 14293.4 14379.7 14396.7 14409.8 14416.4 11115.0 11166.8 11168.9 11179.4 11207.6 18656.6 18834.2 18932.0 18952.8 19642.2 6103.9 6235.3 6249.5 6256.7 6279.0 954.1 1023.0 1050.8 1099.3 1144.2 28133.3 28829.3 28957.9 29104.7 29535.1 1079.7 1083.2 1087.8 1123.5 1140.2 14967.9 15135.2 15153.7 15211.4 15406.5 493721.0 831879.0 839079.0 845142.0 848633.0 50539.5 51176.0 51222.0 51293.5 51424.2 1331.3 1334.5 1335.2 1340.1 1394.6 28848.3 29058.7 29279.6 29417.7 29771.7 42756.4 43171.1 43203.0 43283.8 43542.6 309886.0 310516.0 310896.0 313093.0 313551.0 26873.2 27004.7 27307.5 27731.4 27779.7 41249.0 41555.4 41734.6 41972.8 42848.4 35203.6 35749.5 35757.5 35947.0 36236.3 35077.1 35404.8 35448.6 35599.8 35876.5 31549.5 37126.3 37508.6 37778.6 38099.4 29444.9 30206.0 30495.7 30691.5 30761.0 24542.8 24748.9 25392.6 25435.4 25471.1 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | [#2] jquery.html Cycles:180 Average:27497.66 Median:19660.14 stddev:75733.13 (385.2%) stddev-sans-first:75918.24 18:20:49 INFO - PROCESS | 27203 | Values: 394.5 397.3 401.7 404.6 407.9 14389.9 16500.5 17954.3 18628.7 18654.5 15714.3 15820.0 15936.2 16652.3 16691.8 5455.9 5658.9 5663.9 5667.6 5670.7 5366.6 5652.9 5657.2 5657.7 5666.7 5592.7 5606.7 5608.9 5658.2 5675.7 5503.5 5596.9 5683.3 5697.3 5698.2 5113.1 5117.4 5136.8 5143.1 5147.5 21147.4 21443.2 21968.7 21971.5 22038.2 2258.4 2359.3 2417.2 2533.7 2713.7 9933.3 10539.5 10892.5 10950.8 13567.4 2009.5 2011.2 2055.7 2101.7 2114.7 76027.2 76319.6 76565.6 78285.6 79335.0 10707.5 10816.2 10886.8 11136.3 11208.4 14923.9 15032.4 15066.2 15099.9 15501.2 6538.5 6565.9 7558.1 7897.8 7967.5 7345.5 7426.4 7443.4 7464.3 7557.1 4621.7 4707.2 4759.8 4762.3 5041.7 1924.1 1981.0 1984.4 1988.7 2007.6 941.4 949.4 984.6 1011.4 1049.3 17799.4 17907.0 17927.8 18399.9 18617.3 948.9 993.9 998.1 1009.4 1026.2 10305.0 10407.3 10490.8 10545.0 10565.7 435818.0 438060.0 441616.0 473181.3 500302.0 89218.6 91991.5 92192.0 92834.5 92988.0 945.4 973.3 973.7 973.7 981.5 16057.4 16285.4 16292.8 16395.3 16457.3 37143.7 37749.8 38805.8 38852.4 38925.8 55656.7 56062.4 56068.7 56084.0 56136.4 9091.4 9151.6 9190.2 9334.3 9343.3 12429.5 13025.2 13136.9 13256.0 13276.9 10948.4 11139.9 11141.2 11256.4 11357.9 6379.9 9199.7 10391.2 10568.9 10983.2 11112.2 11268.0 11336.7 11518.5 11597.1 8948.5 8951.1 9089.9 9105.2 9216.2 10269.8 11193.6 11262.7 11320.7 11357.5 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | [#3] mootools.html Cycles:180 Average:5606.04 Median:4899.78 stddev:3151.08 (64.3%) stddev-sans-first:3143.18 18:20:49 INFO - PROCESS | 27203 | Values: 1284.6 1307.9 1326.4 1508.4 1522.7 7448.5 7789.7 7887.4 7909.8 8071.6 7327.4 7961.6 8085.2 8155.9 8252.8 2368.9 2411.8 2412.1 2420.6 2425.3 2398.8 2400.7 2419.7 2422.9 2426.1 2353.6 2368.6 2372.3 2373.3 2381.0 2382.1 2387.5 2388.3 2401.6 2417.3 2367.6 2368.7 2370.6 2376.2 2382.7 8442.4 8517.9 8519.4 8526.7 8542.5 5114.8 5127.1 5201.6 5229.5 5248.7 3420.0 3452.9 3502.3 3516.9 3570.3 1140.9 1171.9 1181.3 1184.9 1204.3 12042.8 12273.8 12279.8 12304.4 12304.9 4686.7 4726.9 4730.1 4765.6 4809.1 10431.4 10443.6 10493.1 10508.7 10514.7 5221.1 5278.8 5290.8 5296.7 5304.2 4123.9 4547.5 4639.9 4644.3 4815.2 3794.4 3834.7 3850.4 3871.7 3898.2 1318.7 1331.9 1333.2 1350.1 1350.5 882.6 885.0 885.0 889.2 912.7 5510.6 5603.5 5643.4 5646.7 5650.8 976.7 996.1 996.4 1010.3 1039.4 3669.0 3794.0 3795.3 3800.3 3818.3 10131.1 10571.2 10661.1 10664.3 10722.9 10473.8 10484.8 10490.9 10505.9 10547.9 7999.5 7999.8 8003.5 8092.8 8109.3 5523.6 5526.3 5577.2 5629.3 5670.0 9326.2 9507.0 9561.1 9655.9 9663.5 9537.0 9651.5 9770.1 9799.0 10009.9 6961.5 7281.6 7480.2 7767.1 7870.0 8162.0 8205.8 8208.8 8233.3 8275.8 7110.3 7136.2 7149.3 7311.2 7433.9 7106.9 7166.9 7243.3 7272.7 7386.3 6593.5 6596.4 6626.3 6636.3 6678.0 4787.1 4902.9 4933.3 4947.3 4989.3 4861.3 4898.5 4901.1 4905.1 4931.4 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | [#4] prototype.html Cycles:180 Average:5895.64 Median:6805.30 stddev:1807.53 (26.6%) stddev-sans-first:1764.13 18:20:49 INFO - PROCESS | 27203 | Values: 355.9 357.5 361.7 363.0 367.4 6582.1 6603.1 6649.8 6667.6 6667.7 6585.5 6590.4 6596.3 6604.8 6612.2 4670.6 4862.8 5037.1 5049.3 5061.5 4996.9 5043.2 5045.0 5046.7 5047.1 4994.4 5004.9 5009.2 5009.5 5014.3 4998.4 5016.3 5017.3 5017.5 5019.6 4415.4 4416.0 4417.6 4421.6 4425.3 6086.2 6331.6 6360.4 6379.0 6420.8 5781.5 5807.9 5811.9 5815.0 5815.5 7014.4 7014.9 7019.2 7020.7 7028.1 4789.5 4802.9 4802.9 4817.1 4817.8 7163.8 7282.7 7301.8 7329.4 7362.2 4875.3 4911.3 4914.2 4923.6 4928.5 4944.3 4977.0 4979.4 4991.7 4996.0 5787.4 5788.3 5789.5 5791.3 5803.3 7077.4 7081.6 7085.6 7088.7 7103.6 1732.3 1759.8 1768.2 1771.6 1773.0 1126.5 1147.6 1149.2 1153.5 1154.4 6821.7 6842.2 6847.3 6849.5 6852.6 7158.5 7246.2 7306.9 7321.0 7371.8 6870.5 6878.3 6880.6 6886.3 6897.2 3943.9 3981.8 3983.2 3989.3 3993.2 7614.6 7633.1 7640.5 7642.2 7657.7 7294.7 7524.3 7536.1 7547.8 7582.1 7632.2 7639.7 7646.6 7652.3 7663.7 7255.7 7332.7 7333.3 7344.8 7344.9 7163.5 7188.1 7224.7 7234.7 7263.2 7305.6 7557.1 7635.2 7653.5 7693.7 4862.2 4910.2 4939.8 4949.3 4952.4 7303.2 7327.4 7332.2 7337.7 7339.1 7116.9 7197.6 7203.5 7207.7 7213.0 6968.8 7062.6 7068.7 7077.3 7085.5 6937.4 6941.0 6942.4 6953.7 6973.2 7122.1 7126.7 7127.2 7133.5 7136.9 6788.9 6867.9 6909.8 6929.7 6935.5 18:20:49 INFO - PROCESS | 27203 | 18:20:49 INFO - PROCESS | 27203 | [#5] yui.html Cycles:180 Average:14545.14 Median:25989.78 stddev:16871.59 (64.9%) stddev-sans-first:16886.88 18:20:49 INFO - PROCESS | 27203 | Values: 699.1 734.2 737.0 757.6 770.9 14467.9 14869.4 14899.7 14975.3 15020.5 13265.7 13267.5 13307.5 13336.5 14320.2 593.8 601.1 608.5 609.5 613.9 610.0 610.7 611.4 611.4 620.4 651.7 651.8 652.7 655.9 673.6 646.1 646.1 647.7 648.9 652.5 479.6 479.8 480.3 481.7 483.6 13411.9 13538.3 13541.1 13567.6 13608.6 13110.2 13168.1 13336.4 13451.9 13573.9 11846.0 11909.2 12162.9 12363.9 12456.7 3822.4 3852.0 3869.5 3880.8 3941.3 57185.4 57320.7 57347.4 57446.7 57711.7 10313.3 10729.8 10935.8 10980.1 11025.1 16641.8 16760.3 16807.3 16827.1 16842.9 8187.0 8199.0 8274.6 8289.0 8437.9 4797.1 4808.9 4849.8 4890.5 4991.9 3203.4 3233.6 3233.9 3259.3 3269.3 616.3 616.9 618.7 620.5 626.1 692.3 743.4 761.5 778.6 781.6 9301.9 9666.4 9877.3 9879.9 9953.5 779.6 790.1 816.2 819.0 824.6 4480.5 4561.7 4585.7 4624.2 4700.3 57708.7 58185.7 58380.0 58384.1 59160.0 51208.5 52467.7 52637.5 53209.7 54577.0 46488.8 46882.8 47375.8 48444.3 48656.8 8799.6 9120.6 9185.9 9235.0 9272.9 34778.7 35796.1 37007.4 37079.3 37199.8 44006.3 44247.3 47279.3 48351.8 49115.8 10619.8 10682.1 10762.6 10866.9 10904.9 12670.4 12978.9 13039.1 13151.2 13845.6 11503.9 11633.7 11686.5 11779.3 12126.7 12059.2 12251.8 12284.1 12371.0 12543.8 10949.3 11234.2 11311.2 11356.4 11364.6 9970.2 9997.2 10431.0 10472.6 10834.0 9417.0 10002.6 10238.6 11161.2 11197.9 18:20:49 INFO - PROCESS | 27203 | -------- Summary: end -------- 18:20:49 INFO - PROCESS | 27203 | 18:20:50 INFO - TEST-INFO | 27203: exit 0 18:20:50 INFO - TEST-OK | dromaeo_css | took 1110635ms 18:20:50 INFO - TEST-START | kraken 18:20:50 INFO - operating with platform_type : linux_ 18:20:50 INFO - Initialising browser for kraken test... 18:20:50 INFO - TEST-INFO | started process 27450 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3htLBS/profile http://localhost:51290/getInfo.html) 18:20:54 INFO - PROCESS | 27450 | 18:20:54 INFO - PROCESS | 27450 | (process:27530): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:20:55 INFO - PROCESS | 27450 | __metrics Screen width/height:1600/1200 18:20:55 INFO - PROCESS | 27450 | colorDepth:24 18:20:55 INFO - PROCESS | 27450 | Browser inner width/height: 1024/697 18:20:55 INFO - PROCESS | 27450 | __metrics 18:20:56 ERROR - PROCESS | 27450 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 18:20:56 INFO - TEST-INFO | 27450: exit 0 18:20:56 INFO - Browser initialized. 18:20:56 INFO - Running cycle 1/1 for kraken test... 18:20:56 INFO - TEST-INFO | started process 27581 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3htLBS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:20:58 INFO - PROCESS | 27581 | 18:20:58 INFO - PROCESS | 27581 | (process:27652): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:21:08 INFO - PROCESS | 27581 | RSS: Main: 151879680 18:21:08 INFO - PROCESS | 27581 | 18:21:11 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51290/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:12 INFO - PROCESS | 27581 | RSS: Main: 156925952 18:21:12 INFO - PROCESS | 27581 | 18:21:14 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51290/tests/kraken/driver.html?testName=audio-dft) 18:21:15 INFO - PROCESS | 27581 | RSS: Main: 157089792 18:21:15 INFO - PROCESS | 27581 | 18:21:18 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51290/tests/kraken/driver.html?testName=audio-fft) 18:21:18 INFO - PROCESS | 27581 | RSS: Main: 157274112 18:21:18 INFO - PROCESS | 27581 | 18:21:20 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51290/tests/kraken/driver.html?testName=audio-oscillator) 18:21:21 INFO - PROCESS | 27581 | RSS: Main: 156893184 18:21:21 INFO - PROCESS | 27581 | 18:21:23 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51290/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:24 INFO - PROCESS | 27581 | RSS: Main: 156848128 18:21:24 INFO - PROCESS | 27581 | 18:21:27 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51290/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:27 INFO - PROCESS | 27581 | RSS: Main: 156884992 18:21:27 INFO - PROCESS | 27581 | 18:21:31 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51290/tests/kraken/driver.html?testName=imaging-desaturate) 18:21:31 INFO - PROCESS | 27581 | RSS: Main: 156844032 18:21:31 INFO - PROCESS | 27581 | 18:21:34 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51290/tests/kraken/driver.html?testName=json-parse-financial) 18:21:35 INFO - PROCESS | 27581 | RSS: Main: 157446144 18:21:35 INFO - PROCESS | 27581 | 18:21:36 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51290/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:21:37 INFO - PROCESS | 27581 | RSS: Main: 157224960 18:21:37 INFO - PROCESS | 27581 | 18:21:39 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:21:39 INFO - PROCESS | 27581 | RSS: Main: 157478912 18:21:39 INFO - PROCESS | 27581 | 18:21:41 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:21:42 INFO - PROCESS | 27581 | RSS: Main: 157364224 18:21:42 INFO - PROCESS | 27581 | 18:21:44 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:21:45 INFO - PROCESS | 27581 | RSS: Main: 158322688 18:21:45 INFO - PROCESS | 27581 | 18:21:48 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:21:48 INFO - PROCESS | 27581 | RSS: Main: 157659136 18:21:48 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:51290/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51290/tests/kraken/driver.html?testName=ai-astar) 18:21:50 INFO - PROCESS | 27581 | RSS: Main: 159109120 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | __start_tp_report 18:21:50 INFO - PROCESS | 27581 | _x_x_mozilla_page_load 18:21:50 INFO - PROCESS | 27581 | _x_x_mozilla_page_load_details 18:21:50 INFO - PROCESS | 27581 | |i|pagename|runs| 18:21:50 INFO - PROCESS | 27581 | |0;ai-astar;108;114;103;109;111;112;102;109;111;110 18:21:50 INFO - PROCESS | 27581 | |1;audio-beat-detection;165;128;169;183;131;151;148;129;177;129 18:21:50 INFO - PROCESS | 27581 | |2;audio-dft;163;164;163;162;160;161;161;163;161;161 18:21:50 INFO - PROCESS | 27581 | |3;audio-fft;76;76;75;165;77;74;76;77;164;78 18:21:50 INFO - PROCESS | 27581 | |4;audio-oscillator;92;94;158;92;92;93;148;91;92;150 18:21:50 INFO - PROCESS | 27581 | |5;imaging-gaussian-blur;118;118;117;118;117;116;117;118;117;116 18:21:50 INFO - PROCESS | 27581 | |6;imaging-darkroom;148;149;149;149;149;149;148;148;149;147 18:21:50 INFO - PROCESS | 27581 | |7;imaging-desaturate;95;95;96;97;96;96;95;96;95;95 18:21:50 INFO - PROCESS | 27581 | |8;json-parse-financial;55;56;58;56;58;59;55;79;56;54 18:21:50 INFO - PROCESS | 27581 | |9;json-stringify-tinderbox;57;58;58;57;59;58;58;58;58;58 18:21:50 INFO - PROCESS | 27581 | |10;stanford-crypto-aes;73;73;73;72;73;74;74;81;74;73 18:21:50 INFO - PROCESS | 27581 | |11;stanford-crypto-ccm;114;120;112;112;120;113;116;115;130;129 18:21:50 INFO - PROCESS | 27581 | |12;stanford-crypto-pbkdf2;165;159;163;159;159;164;164;157;158;157 18:21:50 INFO - PROCESS | 27581 | |13;stanford-crypto-sha256-iterative;79;64;66;67;66;65;65;65;64;63 18:21:50 INFO - PROCESS | 27581 | __end_tp_report 18:21:50 INFO - PROCESS | 27581 | __start_cc_report 18:21:50 INFO - PROCESS | 27581 | _x_x_mozilla_cycle_collect,856 18:21:50 INFO - PROCESS | 27581 | __end_cc_report 18:21:50 INFO - PROCESS | 27581 | __startTimestamp1464225710775__endTimestamp 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | ------- Summary: start ------- 18:21:50 INFO - PROCESS | 27581 | Number of tests: 14 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.50 stddev:3.78 (3.4%) stddev-sans-first:4.00 18:21:50 INFO - PROCESS | 27581 | Values: 108.0 114.0 103.0 109.0 111.0 112.0 102.0 109.0 111.0 110.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#1] audio-beat-detection Cycles:10 Average:151.00 Median:158.00 stddev:21.41 (13.6%) stddev-sans-first:22.10 18:21:50 INFO - PROCESS | 27581 | Values: 165.0 128.0 169.0 183.0 131.0 151.0 148.0 129.0 177.0 129.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.50 stddev:1.29 (0.8%) stddev-sans-first:1.30 18:21:50 INFO - PROCESS | 27581 | Values: 163.0 164.0 163.0 162.0 160.0 161.0 161.0 163.0 161.0 161.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#3] audio-fft Cycles:10 Average:93.80 Median:76.00 stddev:37.28 (49.1%) stddev-sans-first:38.98 18:21:50 INFO - PROCESS | 27581 | Values: 76.0 76.0 75.0 165.0 77.0 74.0 76.0 77.0 164.0 78.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:92.00 stddev:28.96 (31.5%) stddev-sans-first:29.96 18:21:50 INFO - PROCESS | 27581 | Values: 92.0 94.0 158.0 92.0 92.0 93.0 148.0 91.0 92.0 150.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.50 stddev:0.79 (0.7%) stddev-sans-first:0.78 18:21:50 INFO - PROCESS | 27581 | Values: 118.0 118.0 117.0 118.0 117.0 116.0 117.0 118.0 117.0 116.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:0.71 (0.5%) stddev-sans-first:0.73 18:21:50 INFO - PROCESS | 27581 | Values: 148.0 149.0 149.0 149.0 149.0 149.0 148.0 148.0 149.0 147.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 18:21:50 INFO - PROCESS | 27581 | Values: 95.0 95.0 96.0 97.0 96.0 96.0 95.0 96.0 95.0 95.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:57.00 stddev:7.34 (12.9%) stddev-sans-first:7.66 18:21:50 INFO - PROCESS | 27581 | Values: 55.0 56.0 58.0 56.0 58.0 59.0 55.0 79.0 56.0 54.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#9] json-stringify-tinderbox Cycles:10 Average:57.90 Median:58.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 18:21:50 INFO - PROCESS | 27581 | Values: 57.0 58.0 58.0 57.0 59.0 58.0 58.0 58.0 58.0 58.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:73.50 stddev:2.54 (3.5%) stddev-sans-first:2.67 18:21:50 INFO - PROCESS | 27581 | Values: 73.0 73.0 73.0 72.0 73.0 74.0 74.0 81.0 74.0 73.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#11] stanford-crypto-ccm Cycles:10 Average:118.10 Median:118.00 stddev:6.66 (5.6%) stddev-sans-first:6.89 18:21:50 INFO - PROCESS | 27581 | Values: 114.0 120.0 112.0 112.0 120.0 113.0 116.0 115.0 130.0 129.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.50 Median:161.00 stddev:3.14 (1.9%) stddev-sans-first:2.87 18:21:50 INFO - PROCESS | 27581 | Values: 165.0 159.0 163.0 159.0 159.0 164.0 164.0 157.0 158.0 157.0 18:21:50 INFO - PROCESS | 27581 | 18:21:50 INFO - PROCESS | 27581 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:65.50 stddev:4.58 (7.0%) stddev-sans-first:1.22 18:21:50 INFO - PROCESS | 27581 | Values: 79.0 64.0 66.0 67.0 66.0 65.0 65.0 65.0 64.0 63.0 18:21:50 INFO - PROCESS | 27581 | -------- Summary: end -------- 18:21:50 INFO - PROCESS | 27581 | 18:21:51 INFO - TEST-INFO | 27581: exit 0 18:21:51 INFO - TEST-OK | kraken | took 61582ms 18:21:51 INFO - SUITE-END | took 1172s 18:21:51 INFO - Completed test suite (00:19:32) 18:21:51 INFO - javascript benchmark 18:21:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.332733453309, 3458.982705086475, 3462.532558104657, 3488.511608374826, 3506.3863823830825, 5707.885842283152, 5714.8857022859565, 5740.7129643517865, 5751.338596061454, 5754.827355179341, 5712.628679135863, 5731.340895796985, 5732.742026608802, 5744.138379243105, 5756.942430575693, 5148.047611191922, 5190.143626301675, 5194.454582268845, 5254.31693879797, 5260.842174734764, 5042.672226305297, 5157.458466860982, 5163.741812909339, 5315.920261196085, 5323.574114070865, 5122.078025955327, 5181.041507321158, 5206.869828254305, 5235.790568377261, 5237.8428647140645, 5254.448282930275, 5256.9737151314, 5262.973685131588, 5266.815661451831, 5318.361796584396, 5036.798528058874, 5052.974735126338, 5109.3613298337705, 5158.561522270612, 5225.320708307933, 5546.639468434539, 5586.636868603528, 5595.664260144405, 5600.635958662674, 5669.177969194444, 6051.667158306292, 6057.545684073712, 6070.392960703938, 6096.359882212349, 6101.359357267467, 5970.492508136814, 6047.818565443, 6152.077188421727, 6152.169457123276, 6170.228721409824, 5626.2967129108865, 5644.294463192101, 5651.378348381675, 5653.886922261573, 5680.5455563554815, 6141.662208578482, 6163.414475624809, 6164.907526387108, 6165.445109940127, 6165.537584681167, 5847.181394604759, 5848.122781582797, 5893.5579831512805, 5903.0, 5914.704264786829, 5760.2223699800825, 5764.452377024176, 5783.7397317119985, 5787.450192231731, 5854.824355269348, 5687.971560142173, 5704.857378565569, 5744.080947048452, 5750.281214848144, 5805.651660900359, 5819.88360232793, 5875.6474611523445, 5877.706114694248, 5965.0455927052335, 5967.1347654589845, 4533.297338912408, 4556.908861822745, 4557.863264102082, 4560.17916774977, 4569.200389931749, 4078.57174996627, 4093.7953102344763, 4093.815778289984, 4107.630313271819, 4124.711270211116, 5764.193012978187, 5925.970370148208, 5955.047192449188, 5962.433568810956, 6004.159417681441, 6012.158297838306, 6014.669193194329, 6058.0610005449225, 6075.270967483842, 6086.147939288503, 5736.053551164095, 5825.300963884361, 5830.912536311958, 5831.504322132581, 5846.473817356458, 5262.894742105136, 5324.627276090714, 5329.280547126166, 5350.5184533392185, 5361.544268737201, 6263.498920086382, 6283.0, 6296.590721603081, 6303.2751233607805, 6334.3032266450655, 5949.91075133882, 6083.270007599116, 6102.816915492543, 6115.266168059861, 6128.26460824695, 6223.564350495421, 6284.497240220863, 6288.27684816252, 6289.40250676185, 6292.11910332563, 5949.881002379841, 5950.077737950624, 5957.076653118774, 5967.224260846062, 6041.667708276087, 6011.519078473624, 6071.483923866521, 6096.207493025879, 6102.4507794298725, 6150.600210986271, 5893.263342082239, 5900.734466948912, 5901.3213480448585, 5901.380355062656, 5922.289325280994, 5867.911981320198, 5871.4715675589405, 5874.118882167709, 5877.529797616266, 5886.117082437669, 5734.684592347294, 5743.253377060955, 5861.062230043172, 5906.26171728534, 5960.761569537343, 6006.549508786771, 6018.608790428608, 6024.096385542204, 6029.397060293935, 6050.2437195350585, 5893.086571581412, 5896.9115463268945, 5915.349311575809, 5924.822255332347, 5929.140274660236], "value": 5592.407014458509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.5727354941218, 636.503527248746, 653.6764301670673, 658.246307977366, 660.1286302081248, 33698.15754606136, 33805.0, 33836.83081584595, 34034.0, 34068.65931340686, 36485.817570912106, 36506.81746591263, 36914.81542592283, 36995.26009479809, 37147.0, 8483.406161568693, 8746.125387461267, 8748.650053997864, 8754.912450875474, 8762.824743505125, 8568.871466928002, 8656.0, 8688.826223475528, 8843.11568843117, 8864.202221800037, 8861.069587693297, 8951.328650351217, 8960.64157433705, 8961.327900407496, 8975.910240897605, 8774.341924355698, 8811.206991370744, 8843.248323892445, 8884.644614215424, 8887.24458421035, 8763.342749293828, 8817.29461643067, 8877.023527411979, 8881.689140880038, 8884.822303553958, 36343.09142271438, 36391.81804090989, 36643.4503482448, 36663.0, 36792.26415471702, 3424.5719285089363, 3537.9292414151573, 3555.520004799345, 3565.447355659877, 3585.0678823505814, 28533.57199642007, 28994.985175518756, 29061.85469072662, 29396.0, 29519.409611807645, 4454.554544545552, 4538.296564032547, 4584.633229341644, 4606.055758569519, 4646.186917289495, 292356.0, 298942.0, 298965.0, 299470.99999999785, 299861.0, 56542.717286414125, 56955.71522142363, 57025.71487142538, 57075.0, 57111.714441427524, 55078.72460637671, 55653.0, 55920.720396398574, 55925.7203713987, 56098.158527622116, 14293.428262869369, 14379.712405751827, 14396.712065758835, 14409.78385324221, 14416.351264193136, 11115.0, 11166.776664466664, 11168.944155279172, 11179.385133817555, 11207.551697931991, 18656.626867462575, 18834.246630134916, 18932.0, 18952.810471895104, 19642.214311427382, 6103.877922441527, 6235.282942461584, 6249.500039996789, 6256.718447669775, 6278.968605156945, 954.1269738189479, 1023.0, 1050.8020856223911, 1099.340395762536, 1144.210494758614, 28133.296667583476, 28829.279268018046, 28957.855210724232, 29104.708952910623, 29535.113946581638, 1079.7246702091074, 1083.21466936472, 1087.8422628718793, 1123.5112725964232, 1140.2417392433938, 14967.925160374347, 15135.167565783768, 15153.696926061417, 15211.391544338323, 15406.460773873085, 493721.0, 831879.0, 839079.0, 845142.0, 848633.0, 50539.49460505421, 51176.0, 51222.0, 51293.487065129615, 51424.22863657048, 1331.261150061725, 1334.5129027904939, 1335.1922087137336, 1340.0552610409638, 1394.6443656867636, 28848.278793030342, 29058.7094129056, 29279.56080658749, 29417.705822942353, 29771.702282976894, 42756.358654620824, 43171.13657726891, 43203.0, 43283.78358108315, 43542.56457435512, 309886.0, 310516.0, 310896.0, 313093.0, 313551.0, 26873.193804185907, 27004.72995270022, 27307.453850923266, 27731.44537109287, 27779.722202777713, 41249.0, 41555.37666935059, 41734.582654174286, 41972.79013604912, 42848.35727464028, 35203.64796352004, 35749.46375804417, 35757.46363804493, 35947.0, 36236.27527449383, 35077.12307192341, 35404.822975884956, 35448.64551354557, 35599.754008609576, 35876.461853072746, 31549.526757098203, 37126.25747485089, 37508.624913751606, 37778.62221377861, 38099.42850857184, 29444.852775735984, 30206.0, 30495.695043050175, 30691.539626905167, 30761.0, 24542.83829965848, 24748.87625561861, 25392.619110712985, 25435.36411589725, 25471.10851120202], "value": 18659.92491175925, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.4792873407103, 397.3324814311957, 401.74489199358436, 404.58327922240096, 407.88783084651715, 14389.856101438982, 16500.504984850475, 17954.281828726853, 18628.72056919144, 18654.53363665909, 15714.292856821441, 15820.0, 15936.203189840491, 16652.250648720805, 16691.83308166918, 5455.936092461971, 5658.858528536778, 5663.886722265553, 5667.631603945731, 5670.716464176795, 5366.570674346062, 5652.943470565303, 5657.179708942201, 5657.745401456944, 5666.660000399968, 5592.748326325325, 5606.747696353674, 5608.915866262009, 5658.151277308416, 5675.744591493372, 5503.477169668895, 5596.944030559704, 5683.2895888014, 5697.31632204134, 5698.17376480408, 5113.079645663779, 5117.411497677777, 5136.794528218867, 5143.099957507421, 5147.510986456318, 21147.365579032652, 21443.249486267905, 21968.670469942965, 21971.450713732123, 22038.228661996753, 2258.4241018540165, 2359.28041947206, 2417.2385698504913, 2533.695956485215, 2713.7150599187, 9933.304668673196, 10539.473026348653, 10892.509837057352, 10950.780984380348, 13567.389467473988, 2009.5076706206926, 2011.1754180785808, 2055.650539408289, 2101.7057611934342, 2114.74623045234, 76027.23972760313, 76319.61840190763, 76565.61717191435, 78285.60857195735, 79335.0, 10707.518161682743, 10816.242862999672, 10886.836697449544, 11136.33182009082, 11208.439578021229, 14923.850761492246, 15032.398704051715, 15066.171360575274, 15099.924500377647, 15501.224938753243, 6538.509611779041, 6565.934340656628, 7558.093028836574, 7897.842043159219, 7967.482113662593, 7345.485815992936, 7426.368758655467, 7443.441741869381, 7464.290892365217, 7557.093148822177, 4621.699589526737, 4707.164205959679, 4759.809607615726, 4762.309465127537, 5041.747912604355, 1924.143756028582, 1980.9504762380768, 1984.3550845975117, 1988.7414636097503, 2007.6185524750251, 941.3786900645541, 949.3734135470556, 984.5667906121283, 1011.3881101933372, 1049.338916482611, 17799.377021804175, 17907.0, 17927.820721792876, 18399.908000459913, 18617.255309787728, 948.8624149498285, 993.8608594796734, 998.1216529454034, 1009.3590569988111, 1026.2149455666422, 10304.969503049784, 10407.271490995709, 10490.790184196427, 10545.0, 10565.683029509128, 435818.0, 438060.0, 441616.0, 473181.315961819, 500302.0, 89218.55390723134, 91991.54004229936, 92192.0, 92834.53582732043, 92988.0, 945.3618807305179, 973.2797729679986, 973.6884197056874, 973.72735634024, 981.489625394805, 16057.437989670305, 16285.430009950069, 16292.837071629607, 16395.262213200196, 16457.341706332092, 37143.6999705009, 37749.81125094357, 38805.80597096997, 38852.41721374238, 38925.805370972965, 55656.721716391155, 56062.439375605725, 56068.71965640146, 56084.0, 56136.43863561312, 9091.409058411182, 9151.581504866755, 9190.21883139914, 9334.25325973907, 9343.29925255595, 12429.502819887104, 13025.153365031243, 13136.868631313946, 13256.0, 13276.933615331862, 10948.397838118979, 11139.944300278446, 11141.2201145919, 11256.437178140897, 11357.886421135683, 6379.872402552016, 9199.666048423074, 10391.220658450495, 10568.89431105679, 10983.176261780558, 11112.166587506132, 11268.0, 11336.716582085513, 11518.53925842957, 11597.07223422141, 8948.5078320693, 8951.060138685345, 9089.909100908906, 9105.226055785333, 9216.21662158724, 10269.845952310572, 11193.608223712132, 11262.718432038937, 11320.716982075513, 11357.54569817198], "value": 8943.766998757446, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1284.5504073574248, 1307.9019073569484, 1326.396489597233, 1508.3514088941765, 1522.687848990957, 7448.478606497547, 7789.688412463494, 7887.408444366674, 7909.762707118782, 8071.556064416454, 7327.413806895449, 7961.6019199039965, 8085.151059138794, 8155.877661835078, 8252.752417427488, 2368.917087901928, 2411.8311718179734, 2412.1316326122583, 2420.576399130145, 2425.2845410603913, 2398.760123987605, 2400.6879105716234, 2419.697537807774, 2422.878856057199, 2426.0538390027905, 2353.5528249632603, 2368.6328619064075, 2372.252740386781, 2373.3117395955237, 2380.95238095238, 2382.0709923129907, 2387.462820865309, 2388.3312672451566, 2401.5677178107935, 2417.2627348658652, 2367.5501654685554, 2368.6920700309024, 2370.5969985102574, 2376.192094687797, 2382.7140743110763, 8442.366822488339, 8517.872231916526, 8519.40364174508, 8526.701565445179, 8542.487450752975, 5114.7698353573805, 5127.077126117298, 5201.635885488017, 5229.5293423591675, 5248.685078895241, 3419.9658003419895, 3452.9309413811834, 3502.2820321833965, 3516.9120771980906, 3570.250247448039, 1140.880207578209, 1171.859376874772, 1181.285322379965, 1184.8933595976318, 1204.265398107154, 12042.81935770964, 12273.754524909542, 12279.815802762876, 12304.446299916524, 12304.876951230552, 4686.695364801277, 4726.905461890743, 4730.077634861169, 4765.618750499952, 4809.134355815987, 10431.37411755282, 10443.582256709646, 10493.108085812639, 10508.737281568023, 10514.684559463076, 5221.112410890158, 5278.788848446096, 5290.81482148123, 5296.655717378358, 5304.204369344629, 4123.8556650517685, 4547.545245475426, 4639.907201855944, 4644.349791029259, 4815.2054910940005, 3794.4118661607495, 3834.731568790158, 3850.441685955521, 3871.651551360335, 3898.181381909774, 1318.736252749454, 1331.9067665263533, 1333.2333908002938, 1350.1021820489461, 1350.540816122523, 882.5895958379384, 884.9646014159362, 885.0, 889.213046453893, 912.6577533424966, 5510.586705997066, 5603.467670571289, 5643.351014633287, 5646.661200327993, 5650.830475085671, 976.6923419122953, 996.051687006469, 996.3971797062818, 1010.2673914237118, 1039.417925961464, 3668.9633103669157, 3793.981030094832, 3795.2599243147874, 3800.3349413852466, 3818.3317919364554, 10131.08820206185, 10571.207159462918, 10661.093807026335, 10664.306820056672, 10722.892771072346, 10473.84289235662, 10484.790304193873, 10490.947545262225, 10505.9474702626, 10547.947260263649, 7999.4800337976685, 7999.840003199904, 8003.4797738146835, 8092.757217283491, 8109.270165685117, 5523.613347065828, 5526.336839579116, 5577.191307260344, 5629.26819513477, 5670.0, 9326.160645541933, 9507.0, 9561.139497445263, 9655.855162172433, 9663.516824158905, 9536.952315238657, 9651.517424128626, 9770.120689138012, 9799.0, 10009.899901000897, 6961.477889158232, 7281.635918204174, 7480.2145774695055, 7767.1067827200595, 7870.0, 8161.959190204011, 8205.79485512867, 8208.794780130545, 8233.259006689426, 8275.83448331042, 7110.25342339047, 7136.179339376038, 7149.320814522612, 7311.159216690149, 7433.851322973402, 7106.857862842611, 7166.856662866818, 7243.275672432715, 7272.709091636487, 7386.335229829341, 6593.538452308292, 6596.406323430722, 6626.337366263335, 6636.336366363325, 6678.031685405686, 4787.138315103315, 4902.94586663869, 4933.260010998379, 4947.3073769671555, 4989.301497790385, 4861.343718597944, 4898.461169271306, 4901.09329773993, 4905.092557876662, 4931.408231012158], "value": 4529.948800283119, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9341521818463, 357.545916685809, 361.6979821848756, 362.9600743918169, 367.3717942318635, 6582.111414958979, 6603.141591593087, 6649.767258145966, 6667.566608170471, 6667.666616669171, 6585.473162147029, 6590.373914478129, 6596.340365963413, 6604.834879128012, 6612.206535215781, 4670.602998745102, 4862.805487780484, 5037.06814239365, 5049.318342023835, 5061.468545802693, 4996.850094497171, 5043.193089105744, 5045.041442126003, 5046.697198168104, 5047.066292735837, 4994.375703037121, 5004.872929005176, 5009.198528235483, 5009.474005229453, 5014.2979982802435, 4998.375203099613, 5016.347874776292, 5017.297578339036, 5017.49825017499, 5019.623528235397, 4415.381846541487, 4416.028473735773, 4417.580329868657, 4421.60205581496, 4425.291953287458, 6086.2087928569, 6331.55679102463, 6360.427561519486, 6378.968105159491, 6420.839479013015, 5781.53747700183, 5807.941920580783, 5811.912821307642, 5814.97092514539, 5815.534757219412, 7014.403775679073, 7014.92985070153, 7019.157701075853, 7020.719171233144, 7028.051213086219, 4789.544993225672, 4802.879928001793, 4802.927956080626, 4817.108834865526, 4817.807287708488, 7163.820904477378, 7282.672279747424, 7301.817454563678, 7329.377002954702, 7362.190159082496, 4875.317455556225, 4911.287863259808, 4914.238293064581, 4923.556879880828, 4928.507149285043, 4944.332515110486, 4976.950230497721, 4979.402471703419, 4991.675541089818, 4995.9500404996215, 5787.392323806024, 5788.334341550775, 5789.536837053026, 5791.305043394819, 5803.332616749128, 7077.433805295667, 7081.645917704094, 7085.610291433917, 7088.681009354591, 7103.573785572882, 1732.3157352845808, 1759.7888253409417, 1768.1689605885213, 1771.5748220427004, 1772.9645407092044, 1126.4818183635455, 1147.632757517603, 1149.172595731089, 1153.4751687981948, 1154.440096553171, 6821.727130914807, 6842.178938527309, 6847.281035491202, 6849.48628852838, 6852.588844669236, 7158.463115266376, 7246.166690830517, 7306.9404936283945, 7321.0, 7371.778846634609, 6870.519063665495, 6878.312168783082, 6880.58716477013, 6886.2769409212315, 6897.172339319213, 3943.901402464904, 3981.8009099544906, 3983.1635356575443, 3989.3218152913496, 3993.201359728066, 7614.58119803405, 7633.122190948001, 7640.465167438337, 7642.19756925526, 7657.731979380695, 7294.671739771723, 7524.322810947041, 7536.0956685198125, 7547.7735667928955, 7582.090149182133, 7632.236776322435, 7639.69441222356, 7646.579438130845, 7652.273034061755, 7663.655135518915, 7255.673494692646, 7332.743353982585, 7333.339999399866, 7344.816379590553, 7344.8898266524975, 7163.534370265916, 7188.137423509213, 7224.711011559479, 7234.74678386254, 7263.164736055422, 7305.634718263959, 7557.05536807899, 7635.160132385544, 7653.540787552764, 7693.692252309845, 4862.173430516891, 4910.21436570147, 4939.827106051271, 4949.257611358325, 4952.380952380952, 7303.233160518069, 7327.377172940147, 7332.193458719643, 7337.6698048589005, 7339.082614673166, 7116.928830711627, 7197.568145911262, 7203.531770434905, 7207.675654595451, 7212.963935180291, 6968.79093627192, 7062.646867656699, 7068.68190931399, 7077.256888026889, 7085.504014719127, 6937.375636192767, 6940.9652951734915, 6942.375186233263, 6953.6523173842115, 6973.163220413583, 7122.074130363019, 7126.679299431435, 7127.216006239413, 7133.464990125865, 7136.85726285461, 6788.864222715617, 6867.931320686729, 6909.827254318682, 6929.68816403253, 6935.479839011993], "value": 5300.452790470951, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.1226011355748, 734.177720952533, 736.9705211791533, 757.5606148433909, 770.8728059870122, 14467.855321446783, 14869.405223791062, 14899.702005959874, 14975.251237438126, 15020.549383518512, 13265.734685306288, 13267.469301227964, 13307.534236301732, 13336.466541338335, 14320.212388318665, 593.7535922592119, 601.0653433910268, 608.4888693497484, 609.5002098279413, 613.9447449729523, 610.0239616613427, 610.7434877351501, 611.4344231585787, 611.440531913301, 620.4478014567017, 651.6904470376556, 651.8272657745702, 652.725855140842, 655.8950567909136, 673.6497021548792, 646.0954663471131, 646.1083704487777, 647.7085311609748, 648.8604949935763, 652.5236577298575, 479.6306843730343, 479.81287297953827, 480.3083559674058, 481.6508031677041, 483.6469377354551, 13411.865881341158, 13538.323083845868, 13541.05212635116, 13567.59297221085, 13608.591742247749, 13110.213387196798, 13168.144070635379, 13336.399862006234, 13451.855101654139, 13573.86426135736, 11846.0, 11909.225900316538, 12162.878371216351, 12363.876361236453, 12456.688582785413, 3822.4075268333454, 3851.9807400963095, 3869.535655721303, 3880.825362858678, 3941.310270702644, 57185.42814571884, 57320.713396433166, 57347.42652573463, 57446.71276643632, 57711.71144144337, 10313.329633573794, 10729.83905241422, 10935.781284374427, 10980.121590272758, 11025.062869656009, 16641.750373744162, 16760.329586816635, 16807.327706891832, 16827.074510901773, 16842.915785420995, 8186.959065204635, 8198.987101763863, 8274.586270686563, 8289.0467596226, 8437.915620843836, 4797.088553174885, 4808.927866082012, 4849.781759820817, 4890.535399137077, 4991.875203119951, 3203.3593281343824, 3233.579634647481, 3233.935321293561, 3259.3481303739345, 3269.3297873936262, 616.3282022595303, 616.9105479705418, 618.6566455617086, 620.5283984172062, 626.0859145647315, 692.3007762160154, 743.3569961982954, 761.493606751504, 778.5990215039259, 781.6443518199319, 9301.860472092923, 9666.420014799134, 9877.259205559612, 9879.861128456292, 9953.452560109119, 779.5829231361195, 790.1032328307413, 816.1675091406731, 818.9590520473953, 824.6083110522454, 4480.529544397857, 4561.68068235227, 4585.656075794329, 4624.167649822997, 4700.271457924027, 57708.71145644245, 58185.70907145522, 58380.0, 58384.124238136465, 59160.0, 51208.48791512111, 52467.73766131145, 52637.47362526402, 53209.733951329996, 54577.0, 46488.767556162005, 46882.765586172536, 47375.76312118417, 48444.273335900696, 48656.7567162169, 8799.604017819085, 9120.589573469077, 9185.908140918773, 9235.0, 9272.860907086533, 34778.652213477544, 35796.105097372776, 37007.44488832616, 37079.25841483209, 37199.81400092982, 44006.339904900815, 44247.33628995632, 47279.29081063718, 48351.75824120857, 49115.75442122766, 10619.840702389316, 10682.145428365868, 10762.569497220024, 10866.94566527194, 10904.94547527289, 12670.366481676063, 12978.935105324414, 13039.087263891437, 13151.21092734439, 13845.584632461043, 11503.884961150281, 11633.709157271136, 11686.474108665301, 11779.293242405483, 12126.696832579257, 12059.21615095016, 12251.816222756488, 12284.1401101922, 12371.0, 12543.811842822548, 10949.343039417661, 11234.213605047567, 11311.151663625425, 11356.375399353115, 11364.602238921598, 9970.152537034433, 9997.200223982209, 10431.0, 10472.581096756348, 10834.0, 9417.0, 10002.649907253211, 10238.590456381662, 11161.162912781412, 11197.944010279896], "value": 6428.252388341673, "unit": "score"}], "name": "dromaeo_css", "value": 7240.3664356773725, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 114.0, 103.0, 109.0, 111.0, 112.0, 102.0, 109.0, 111.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 128.0, 169.0, 183.0, 131.0, 151.0, 148.0, 129.0, 177.0, 129.0], "value": 151.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 162.0, 160.0, 161.0, 161.0, 163.0, 161.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 165.0, 77.0, 74.0, 76.0, 77.0, 164.0, 78.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 158.0, 92.0, 92.0, 93.0, 148.0, 91.0, 92.0, 150.0], "value": 110.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 116.0, 117.0, 118.0, 117.0, 116.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 149.0, 149.0, 149.0, 148.0, 148.0, 149.0, 147.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 58.0, 56.0, 58.0, 59.0, 55.0, 79.0, 56.0, 54.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 57.0, 59.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 72.0, 73.0, 74.0, 74.0, 81.0, 74.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 112.0, 112.0, 120.0, 113.0, 116.0, 115.0, 130.0, 129.0], "value": 118.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 163.0, 159.0, 159.0, 164.0, 164.0, 157.0, 158.0, 157.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 64.0, 66.0, 67.0, 66.0, 65.0, 65.0, 65.0, 64.0, 63.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1522.6000000000001, "alertThreshold": 2.0}]} 18:21:52 INFO - Return code: 0 18:21:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:21:52 ERROR - # TBPL SUCCESS # 18:21:52 INFO - Running post-action listener: _resource_record_post_action 18:21:52 INFO - Running post-run listener: _resource_record_post_run 18:21:53 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 262144; Write bytes: 258314240; Read time: 16; Write time: 344156 18:21:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 14376960; Read time: 0; Write time: 41232 18:21:53 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 243937280; Read time: 16; Write time: 302924 18:21:53 INFO - Running post-run listener: _upload_blobber_files 18:21:53 INFO - Blob upload gear active. 18:21:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:21:53 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 18:21:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:21:53 INFO - (blobuploader) - INFO - Open directory for files ... 18:21:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:21:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:21:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:21:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:21:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:21:54 INFO - (blobuploader) - INFO - Done attempting. 18:21:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:21:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:21:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:21:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:21:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:21:55 INFO - (blobuploader) - INFO - Done attempting. 18:21:55 INFO - (blobuploader) - INFO - Iteration through files over. 18:21:55 INFO - Return code: 0 18:21:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:21:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:21:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/247ee87bd32ce43f612a465304a3304d4d02d4b1917f62e6fe073e04e343e45ca5b690832ebe6d6318d1bdad5be5bf9b97405f42bfa9cca0646a55c1dd93944a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902cbcf82be5a577a83495cd2dc0925714881d4e124921ea22555e882b57543dceb7dd3096ab41621093387c95b0211ef3b5e91f4677deac7fbefa4935787043"} 18:21:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:21:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:21:55 INFO - Contents: 18:21:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/247ee87bd32ce43f612a465304a3304d4d02d4b1917f62e6fe073e04e343e45ca5b690832ebe6d6318d1bdad5be5bf9b97405f42bfa9cca0646a55c1dd93944a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902cbcf82be5a577a83495cd2dc0925714881d4e124921ea22555e882b57543dceb7dd3096ab41621093387c95b0211ef3b5e91f4677deac7fbefa4935787043"} 18:21:55 INFO - Running post-run listener: copy_logs_to_upload_dir 18:21:55 INFO - Copying logs to upload dir... 18:21:55 INFO - mkdir: /builds/slave/test/build/upload/logs 18:21:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.087002 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-25 18:21:55.382785) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 18:21:55.386294) ========= 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=df17445c3590ff7897617a0e00000225-1464222255.740603-218036450 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/247ee87bd32ce43f612a465304a3304d4d02d4b1917f62e6fe073e04e343e45ca5b690832ebe6d6318d1bdad5be5bf9b97405f42bfa9cca0646a55c1dd93944a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902cbcf82be5a577a83495cd2dc0925714881d4e124921ea22555e882b57543dceb7dd3096ab41621093387c95b0211ef3b5e91f4677deac7fbefa4935787043"} build_url:https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/247ee87bd32ce43f612a465304a3304d4d02d4b1917f62e6fe073e04e343e45ca5b690832ebe6d6318d1bdad5be5bf9b97405f42bfa9cca0646a55c1dd93944a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902cbcf82be5a577a83495cd2dc0925714881d4e124921ea22555e882b57543dceb7dd3096ab41621093387c95b0211ef3b5e91f4677deac7fbefa4935787043"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 18:21:55.417842) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:21:55.418233) ========= 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=df17445c3590ff7897617a0e00000225-1464222255.740603-218036450 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:21:55.499747) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 18:21:55.500127) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 18:21:55.500460) ========= ========= Total master_lag: 0.22 =========