builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1461647147.2 results: success (0) buildid: 20160425193111 builduid: 6d74e9ca59d6446f930933cb67d43374 revision: cfc7ebe592937ad937cebe04fdad4213eee72fae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.201538) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.202266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.202784) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.251230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.251485) ========= 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=20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 22:05:47-- 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% 26.9M=0s 2016-04-25 22:05:47 (26.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.071310 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.340368) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.340656) ========= 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=20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015804 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.393746) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.394064) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cfc7ebe592937ad937cebe04fdad4213eee72fae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cfc7ebe592937ad937cebe04fdad4213eee72fae --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=20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 22:05:47,588 truncating revision to first 12 chars 2016-04-25 22:05:47,588 Setting DEBUG logging. 2016-04-25 22:05:47,588 attempt 1/10 2016-04-25 22:05:47,588 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cfc7ebe59293?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 22:05:47,861 unpacking tar archive at: mozilla-central-cfc7ebe59293/testing/mozharness/ program finished with exit code 0 elapsedTime=0.566464 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.973216) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.973520) ========= script_repo_revision: cfc7ebe592937ad937cebe04fdad4213eee72fae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.973928) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.974191) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 22:05:47.983665) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-04-25 22:05:47.983963) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:05:48 INFO - MultiFileLogger online at 20160425 22:05:48 in /builds/slave/test 22:05:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:05:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:05:48 INFO - {'append_to_log': False, 22:05:48 INFO - 'base_work_dir': '/builds/slave/test', 22:05:48 INFO - 'blob_upload_branch': 'Firefox', 22:05:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:05:48 INFO - 'branch': 'Firefox', 22:05:48 INFO - 'buildbot_json_path': 'buildprops.json', 22:05:48 INFO - 'config_files': ('talos/linux_config.py',), 22:05:48 INFO - 'default_actions': ('clobber', 22:05:48 INFO - 'read-buildbot-config', 22:05:48 INFO - 'download-and-extract', 22:05:48 INFO - 'populate-webroot', 22:05:48 INFO - 'create-virtualenv', 22:05:48 INFO - 'install', 22:05:48 INFO - 'run-tests'), 22:05:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:05:48 INFO - 'download_minidump_stackwalk': True, 22:05:48 INFO - 'download_symbols': 'ondemand', 22:05:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:05:48 INFO - 'tooltool.py': '/tools/tooltool.py', 22:05:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:05:48 INFO - '/tools/misc-python/virtualenv.py')}, 22:05:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:05:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:05:48 INFO - 'installer_path': 'installer.exe', 22:05:48 INFO - 'log_level': 'info', 22:05:48 INFO - 'log_name': 'talos', 22:05:48 INFO - 'log_to_console': True, 22:05:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:05:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:05:48 INFO - 'opt_config_files': (), 22:05:48 INFO - 'pip_index': False, 22:05:48 INFO - 'sps_profile': False, 22:05:48 INFO - 'sps_profile_interval': 0, 22:05:48 INFO - 'suite': 'dromaeojs-e10s', 22:05:48 INFO - 'system_bits': '32', 22:05:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:05:48 INFO - 'title': 'talos-linux64-ix-015', 22:05:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:05:48 INFO - 'use_talos_json': True, 22:05:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:05:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:05:48 INFO - 'work_dir': 'build'} 22:05:48 INFO - ##### 22:05:48 INFO - ##### Running clobber step. 22:05:48 INFO - ##### 22:05:48 INFO - Running pre-action listener: _resource_record_pre_action 22:05:48 INFO - Running main action method: clobber 22:05:48 INFO - rmtree: /builds/slave/test/build 22:05:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:05:49 INFO - Running post-action listener: _resource_record_post_action 22:05:49 INFO - ##### 22:05:49 INFO - ##### Running read-buildbot-config step. 22:05:49 INFO - ##### 22:05:49 INFO - Running pre-action listener: _resource_record_pre_action 22:05:49 INFO - Running main action method: read_buildbot_config 22:05:49 INFO - Using buildbot properties: 22:05:49 INFO - { 22:05:49 INFO - "project": "", 22:05:49 INFO - "product": "firefox", 22:05:49 INFO - "script_repo_revision": "production", 22:05:49 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:05:49 INFO - "repository": "", 22:05:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 22:05:49 INFO - "buildid": "20160425193111", 22:05:49 INFO - "pgo_build": "True", 22:05:49 INFO - "basedir": "/builds/slave/test", 22:05:49 INFO - "buildnumber": 718, 22:05:49 INFO - "slavename": "talos-linux64-ix-015", 22:05:49 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:05:49 INFO - "platform": "ubuntu64_hw", 22:05:49 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:05:49 INFO - "branch": "mozilla-central", 22:05:49 INFO - "repo_path": "mozilla-central", 22:05:49 INFO - "revision": "cfc7ebe592937ad937cebe04fdad4213eee72fae", 22:05:49 INFO - "stage_platform": "linux64-pgo", 22:05:49 INFO - "builduid": "6d74e9ca59d6446f930933cb67d43374", 22:05:49 INFO - "slavebuilddir": "test" 22:05:49 INFO - } 22:05:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:05:49 INFO - Running post-action listener: _resource_record_post_action 22:05:49 INFO - ##### 22:05:49 INFO - ##### Running download-and-extract step. 22:05:49 INFO - ##### 22:05:49 INFO - Running pre-action listener: _resource_record_pre_action 22:05:49 INFO - Running main action method: download_and_extract 22:05:49 INFO - mkdir: /builds/slave/test/build/tests 22:05: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')]} 22:05:49 INFO - https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:05:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:05:55 INFO - Downloaded 1361 bytes. 22:05:55 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:55 INFO - Using the following test package requirements: 22:05:55 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:55 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:05:55 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:05:55 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'jsshell-linux-x86_64.zip'], 22:05:55 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:05:55 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:55 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:05:55 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:05:55 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:05:55 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:55 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:05:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:05:55 INFO - https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:06:05 INFO - Downloaded 23862654 bytes. 22:06:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:06:07 INFO - Return code: 0 22:06:07 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:06:07 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')]} 22:06:07 INFO - https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:06:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:06:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:06:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:06:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:06:12 INFO - Downloaded 23862654 bytes. 22:06:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:06:13 INFO - Return code: 0 22:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:13 INFO - https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:06:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:06:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:06:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:06:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:06:15 INFO - Downloaded 10840145 bytes. 22:06:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:06:15 INFO - Return code: 0 22:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:15 INFO - https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:06:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:06:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:06:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:06:17 INFO - Downloaded 58370244 bytes. 22:06:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:06:17 INFO - mkdir: /builds/slave/test/properties 22:06:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:06:17 INFO - Writing to file /builds/slave/test/properties/build_url 22:06:17 INFO - Contents: 22:06:17 INFO - build_url:https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:06:18 INFO - Running post-action listener: _resource_record_post_action 22:06:18 INFO - Running post-action listener: set_extra_try_arguments 22:06:18 INFO - ##### 22:06:18 INFO - ##### Running populate-webroot step. 22:06:18 INFO - ##### 22:06:18 INFO - Running pre-action listener: _resource_record_pre_action 22:06:18 INFO - Running main action method: populate_webroot 22:06:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:06:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:06:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:06:18 INFO - '--tppagecycles', 22:06:18 INFO - '7'], 22:06:18 INFO - 'tests': ['tsvgm']}}, 22:06:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:06:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:06:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:06:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:06:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['damp', 'tps']}, 22:06:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'tests': ['damp', 'tps']}, 22:06:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['dromaeo_dom']}, 22:06:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:06:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['a11yr', 22:06:18 INFO - 'ts_paint', 22:06:18 INFO - 'tpaint', 22:06:18 INFO - 'sessionrestore', 22:06:18 INFO - 'sessionrestore_no_auto_restore', 22:06:18 INFO - 'tabpaint']}, 22:06:18 INFO - 'other-e10s': {'tests': ['a11yr', 22:06:18 INFO - 'ts_paint', 22:06:18 INFO - 'tpaint', 22:06:18 INFO - 'sessionrestore', 22:06:18 INFO - 'sessionrestore_no_auto_restore', 22:06:18 INFO - 'tabpaint']}, 22:06:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:06:18 INFO - 'ts_paint', 22:06:18 INFO - 'tpaint', 22:06:18 INFO - 'sessionrestore', 22:06:18 INFO - 'sessionrestore_no_auto_restore', 22:06:18 INFO - 'tabpaint']}, 22:06:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:06:18 INFO - 'ts_paint', 22:06:18 INFO - 'tpaint', 22:06:18 INFO - 'sessionrestore', 22:06:18 INFO - 'sessionrestore_no_auto_restore', 22:06:18 INFO - 'tabpaint']}, 22:06:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['a11yr', 22:06:18 INFO - 'ts_paint', 22:06:18 INFO - 'tpaint', 22:06:18 INFO - 'sessionrestore', 22:06:18 INFO - 'sessionrestore_no_auto_restore', 22:06:18 INFO - 'tabpaint']}, 22:06:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['a11yr', 22:06:18 INFO - 'ts_paint', 22:06:18 INFO - 'tpaint', 22:06:18 INFO - 'sessionrestore', 22:06:18 INFO - 'sessionrestore_no_auto_restore', 22:06:18 INFO - 'tabpaint']}, 22:06:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['tsvgx', 22:06:18 INFO - 'tsvgr_opacity', 22:06:18 INFO - 'tart', 22:06:18 INFO - 'tscrollx', 22:06:18 INFO - 'cart']}, 22:06:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:06:18 INFO - 'tsvgr_opacity', 22:06:18 INFO - 'tart', 22:06:18 INFO - 'tscrollx', 22:06:18 INFO - 'cart']}, 22:06:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'talos_options': ['--disable-e10s'], 22:06:18 INFO - 'tests': ['tp5o']}, 22:06:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'tests': ['tp5o']}, 22:06:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'talos_options': ['--disable-e10s', 22:06:18 INFO - '--xperf_path', 22:06:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:18 INFO - 'tests': ['tp5n']}, 22:06:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:18 INFO - 'talos_options': ['--xperf_path', 22:06:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:18 INFO - 'tests': ['tp5n']}}, 22:06:18 INFO - 'talos.zip': {'path': '', 22:06:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:06:18 INFO - Running post-action listener: _resource_record_post_action 22:06:18 INFO - ##### 22:06:18 INFO - ##### Running create-virtualenv step. 22:06:18 INFO - ##### 22:06:18 INFO - Running pre-action listener: _resource_record_pre_action 22:06:18 INFO - Running main action method: create_virtualenv 22:06:18 INFO - Creating virtualenv /builds/slave/test/build/venv 22:06:18 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 22:06:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:06:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:06:18 INFO - Using real prefix '/usr' 22:06:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:06:18 INFO - Installing distribute.............................................................................................................................................................................................done. 22:06:20 INFO - Installing pip.................done. 22:06:20 INFO - Return code: 0 22:06:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:06: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')]} 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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')]} 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:20 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:20 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 22:06:20 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 22:06:20 INFO - Using env: {'DISPLAY': ':0', 22:06:20 INFO - 'HOME': '/home/cltbld', 22:06:20 INFO - 'LANG': 'en_US.UTF-8', 22:06:20 INFO - 'LANGUAGE': 'en_US:en', 22:06:20 INFO - 'LOGNAME': 'cltbld', 22:06:20 INFO - 'MAIL': '/var/mail/cltbld', 22:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:20 INFO - 'MOZ_NO_REMOTE': '1', 22:06:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:20 INFO - 'NO_EM_RESTART': '1', 22:06:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:20 INFO - 'PWD': '/builds/slave/test', 22:06:20 INFO - 'SHELL': '/bin/bash', 22:06:20 INFO - 'SHLVL': '1', 22:06:20 INFO - 'TERM': 'linux', 22:06:20 INFO - 'TMOUT': '86400', 22:06:20 INFO - 'USER': 'cltbld', 22:06:20 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:20 INFO - '_': '/tools/buildbot/bin/python'} 22:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:20 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 22:06:20 INFO - Cleaning up... 22:06:20 INFO - Return code: 0 22:06:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:06: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')]} 22:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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')]} 22:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:20 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:20 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 22:06:20 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 22:06:20 INFO - Using env: {'DISPLAY': ':0', 22:06:20 INFO - 'HOME': '/home/cltbld', 22:06:20 INFO - 'LANG': 'en_US.UTF-8', 22:06:20 INFO - 'LANGUAGE': 'en_US:en', 22:06:20 INFO - 'LOGNAME': 'cltbld', 22:06:20 INFO - 'MAIL': '/var/mail/cltbld', 22:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:20 INFO - 'MOZ_NO_REMOTE': '1', 22:06:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:20 INFO - 'NO_EM_RESTART': '1', 22:06:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:20 INFO - 'PWD': '/builds/slave/test', 22:06:20 INFO - 'SHELL': '/bin/bash', 22:06:20 INFO - 'SHLVL': '1', 22:06:20 INFO - 'TERM': 'linux', 22:06:20 INFO - 'TMOUT': '86400', 22:06:20 INFO - 'USER': 'cltbld', 22:06:20 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:20 INFO - '_': '/tools/buildbot/bin/python'} 22:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:21 INFO - Downloading/unpacking psutil>=0.7.1 22:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:06:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:23 INFO - Installing collected packages: psutil 22:06:23 INFO - Running setup.py install for psutil 22:06:23 INFO - building 'psutil._psutil_linux' extension 22:06:23 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 22:06:23 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 22:06:23 INFO - building 'psutil._psutil_posix' extension 22:06:23 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 22:06:23 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 22:06:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:24 INFO - Successfully installed psutil 22:06:24 INFO - Cleaning up... 22:06:24 INFO - Return code: 0 22:06:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:06: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')]} 22:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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')]} 22:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:24 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:24 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 22:06:24 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 22:06:24 INFO - Using env: {'DISPLAY': ':0', 22:06:24 INFO - 'HOME': '/home/cltbld', 22:06:24 INFO - 'LANG': 'en_US.UTF-8', 22:06:24 INFO - 'LANGUAGE': 'en_US:en', 22:06:24 INFO - 'LOGNAME': 'cltbld', 22:06:24 INFO - 'MAIL': '/var/mail/cltbld', 22:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:24 INFO - 'MOZ_NO_REMOTE': '1', 22:06:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:24 INFO - 'NO_EM_RESTART': '1', 22:06:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:24 INFO - 'PWD': '/builds/slave/test', 22:06:24 INFO - 'SHELL': '/bin/bash', 22:06:24 INFO - 'SHLVL': '1', 22:06:24 INFO - 'TERM': 'linux', 22:06:24 INFO - 'TMOUT': '86400', 22:06:24 INFO - 'USER': 'cltbld', 22:06:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:24 INFO - '_': '/tools/buildbot/bin/python'} 22:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:06:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:06:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:06:26 INFO - Installing collected packages: mozsystemmonitor 22:06:26 INFO - Running setup.py install for mozsystemmonitor 22:06:26 INFO - Successfully installed mozsystemmonitor 22:06:26 INFO - Cleaning up... 22:06:26 INFO - Return code: 0 22:06:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:06:26 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')]} 22:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:26 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')]} 22:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:26 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:26 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 22:06:26 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 22:06:26 INFO - Using env: {'DISPLAY': ':0', 22:06:26 INFO - 'HOME': '/home/cltbld', 22:06:26 INFO - 'LANG': 'en_US.UTF-8', 22:06:26 INFO - 'LANGUAGE': 'en_US:en', 22:06:26 INFO - 'LOGNAME': 'cltbld', 22:06:26 INFO - 'MAIL': '/var/mail/cltbld', 22:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:26 INFO - 'MOZ_NO_REMOTE': '1', 22:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:26 INFO - 'NO_EM_RESTART': '1', 22:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:26 INFO - 'PWD': '/builds/slave/test', 22:06:26 INFO - 'SHELL': '/bin/bash', 22:06:26 INFO - 'SHLVL': '1', 22:06:26 INFO - 'TERM': 'linux', 22:06:26 INFO - 'TMOUT': '86400', 22:06:26 INFO - 'USER': 'cltbld', 22:06:26 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:26 INFO - '_': '/tools/buildbot/bin/python'} 22:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:26 INFO - Downloading/unpacking blobuploader==1.2.4 22:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:28 INFO - Downloading blobuploader-1.2.4.tar.gz 22:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:06:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:06:28 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 22:06:28 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 22:06:28 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 22:06:28 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 22:06:28 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 22:06:28 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 22:06:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:06:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 INFO - Downloading docopt-0.6.1.tar.gz 22:06:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:06:29 INFO - Installing collected packages: blobuploader, requests, docopt 22:06:29 INFO - Running setup.py install for blobuploader 22:06:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:06:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:06:29 INFO - Running setup.py install for requests 22:06:29 INFO - Running setup.py install for docopt 22:06:30 INFO - Successfully installed blobuploader requests docopt 22:06:30 INFO - Cleaning up... 22:06:30 INFO - Return code: 0 22:06:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:30 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:30 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 22:06:30 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 22:06:30 INFO - Using env: {'DISPLAY': ':0', 22:06:30 INFO - 'HOME': '/home/cltbld', 22:06:30 INFO - 'LANG': 'en_US.UTF-8', 22:06:30 INFO - 'LANGUAGE': 'en_US:en', 22:06:30 INFO - 'LOGNAME': 'cltbld', 22:06:30 INFO - 'MAIL': '/var/mail/cltbld', 22:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:30 INFO - 'MOZ_NO_REMOTE': '1', 22:06:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:30 INFO - 'NO_EM_RESTART': '1', 22:06:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:30 INFO - 'PWD': '/builds/slave/test', 22:06:30 INFO - 'SHELL': '/bin/bash', 22:06:30 INFO - 'SHLVL': '1', 22:06:30 INFO - 'TERM': 'linux', 22:06:30 INFO - 'TMOUT': '86400', 22:06:30 INFO - 'USER': 'cltbld', 22:06:30 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:30 INFO - '_': '/tools/buildbot/bin/python'} 22:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:30 INFO - Running setup.py (path:/tmp/pip-Fsm9WH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:30 INFO - Running setup.py (path:/tmp/pip-F6FcFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:30 INFO - Running setup.py (path:/tmp/pip-HWCOsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:30 INFO - Running setup.py (path:/tmp/pip-73madj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:30 INFO - Running setup.py (path:/tmp/pip-CkfliB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:30 INFO - Running setup.py (path:/tmp/pip-spyd4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:30 INFO - Running setup.py (path:/tmp/pip-cKdK1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:30 INFO - Running setup.py (path:/tmp/pip-MWMZYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:30 INFO - Running setup.py (path:/tmp/pip-mzXTLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:30 INFO - Running setup.py (path:/tmp/pip-_0KvEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:31 INFO - Running setup.py (path:/tmp/pip-SklO7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:31 INFO - Running setup.py (path:/tmp/pip-BZ0OQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:31 INFO - Running setup.py (path:/tmp/pip-iFbcby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:31 INFO - Running setup.py (path:/tmp/pip-ULMfhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:31 INFO - Running setup.py (path:/tmp/pip-O7uP7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:31 INFO - Running setup.py (path:/tmp/pip-9EtTeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:31 INFO - Running setup.py (path:/tmp/pip-wMpVNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:06:31 INFO - Running setup.py install for manifestparser 22:06:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:06:31 INFO - Running setup.py install for mozcrash 22:06:31 INFO - Running setup.py install for mozdebug 22:06:31 INFO - Running setup.py install for mozdevice 22:06:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:06:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:06:31 INFO - Running setup.py install for mozfile 22:06:32 INFO - Running setup.py install for mozhttpd 22:06:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Running setup.py install for mozinfo 22:06:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Running setup.py install for mozInstall 22:06:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Running setup.py install for mozleak 22:06:32 INFO - Running setup.py install for mozlog 22:06:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Running setup.py install for moznetwork 22:06:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Running setup.py install for mozprocess 22:06:32 INFO - Running setup.py install for mozprofile 22:06:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:06:32 INFO - Running setup.py install for mozrunner 22:06:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:06:33 INFO - Running setup.py install for mozscreenshot 22:06:33 INFO - Running setup.py install for moztest 22:06:33 INFO - Running setup.py install for mozversion 22:06:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:06:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:06:33 INFO - Cleaning up... 22:06:33 INFO - Return code: 0 22:06:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:33 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')]} 22:06:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:33 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')]} 22:06:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:33 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:33 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 22:06:33 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 22:06:33 INFO - Using env: {'DISPLAY': ':0', 22:06:33 INFO - 'HOME': '/home/cltbld', 22:06:33 INFO - 'LANG': 'en_US.UTF-8', 22:06:33 INFO - 'LANGUAGE': 'en_US:en', 22:06:33 INFO - 'LOGNAME': 'cltbld', 22:06:33 INFO - 'MAIL': '/var/mail/cltbld', 22:06:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:33 INFO - 'MOZ_NO_REMOTE': '1', 22:06:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:33 INFO - 'NO_EM_RESTART': '1', 22:06:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:33 INFO - 'PWD': '/builds/slave/test', 22:06:33 INFO - 'SHELL': '/bin/bash', 22:06:33 INFO - 'SHLVL': '1', 22:06:33 INFO - 'TERM': 'linux', 22:06:33 INFO - 'TMOUT': '86400', 22:06:33 INFO - 'USER': 'cltbld', 22:06:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:33 INFO - '_': '/tools/buildbot/bin/python'} 22:06:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:33 INFO - Running setup.py (path:/tmp/pip-LkSapP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:33 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)) 22:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:33 INFO - Running setup.py (path:/tmp/pip-YjTUg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:33 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)) 22:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:33 INFO - Running setup.py (path:/tmp/pip-4m8Zcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:33 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)) 22:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:33 INFO - Running setup.py (path:/tmp/pip-5kqfNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:33 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)) 22:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:33 INFO - Running setup.py (path:/tmp/pip-vLCYBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:33 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)) 22:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:33 INFO - Running setup.py (path:/tmp/pip-Pnjoyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:33 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)) 22:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:33 INFO - Running setup.py (path:/tmp/pip-vryWod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:34 INFO - Running setup.py (path:/tmp/pip-5SDjuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:34 INFO - Running setup.py (path:/tmp/pip-t10MKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:34 INFO - Running setup.py (path:/tmp/pip-0wx2wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:34 INFO - Running setup.py (path:/tmp/pip-j4dm9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:34 INFO - Running setup.py (path:/tmp/pip-GRTusr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:34 INFO - Running setup.py (path:/tmp/pip-EB0F1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:34 INFO - Running setup.py (path:/tmp/pip-8p5vnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:34 INFO - Running setup.py (path:/tmp/pip-Px3HcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:34 INFO - Running setup.py (path:/tmp/pip-WWTO58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:34 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)) 22:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:34 INFO - Running setup.py (path:/tmp/pip-YwkbS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:34 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)) 22:06:34 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)) 22:06:34 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)) 22:06:34 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)) 22:06:34 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)) 22:06:34 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)) 22:06:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:36 INFO - Downloading blessings-1.6.tar.gz 22:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:06:36 INFO - Installing collected packages: blessings 22:06:36 INFO - Running setup.py install for blessings 22:06:36 INFO - Successfully installed blessings 22:06:36 INFO - Cleaning up... 22:06:36 INFO - Return code: 0 22:06:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:06:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:37 INFO - Reading from file tmpfile_stdout 22:06:37 INFO - Current package versions: 22:06:37 INFO - argparse == 1.2.1 22:06:37 INFO - blessings == 1.6 22:06:37 INFO - blobuploader == 1.2.4 22:06:37 INFO - docopt == 0.6.1 22:06:37 INFO - manifestparser == 1.1 22:06:37 INFO - mozInstall == 1.12 22:06:37 INFO - mozcrash == 0.17 22:06:37 INFO - mozdebug == 0.1 22:06:37 INFO - mozdevice == 0.48 22:06:37 INFO - mozfile == 1.2 22:06:37 INFO - mozhttpd == 0.7 22:06:37 INFO - mozinfo == 0.9 22:06:37 INFO - mozleak == 0.1 22:06:37 INFO - mozlog == 3.1 22:06:37 INFO - moznetwork == 0.27 22:06:37 INFO - mozprocess == 0.22 22:06:37 INFO - mozprofile == 0.28 22:06:37 INFO - mozrunner == 6.11 22:06:37 INFO - mozscreenshot == 0.1 22:06:37 INFO - mozsystemmonitor == 0.0 22:06:37 INFO - moztest == 0.7 22:06:37 INFO - mozversion == 1.4 22:06:37 INFO - psutil == 3.1.1 22:06:37 INFO - requests == 1.2.3 22:06:37 INFO - wsgiref == 0.1.2 22:06:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:37 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')]} 22:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:37 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')]} 22:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:37 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:37 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 22:06:37 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 22:06:37 INFO - Using env: {'DISPLAY': ':0', 22:06:37 INFO - 'HOME': '/home/cltbld', 22:06:37 INFO - 'LANG': 'en_US.UTF-8', 22:06:37 INFO - 'LANGUAGE': 'en_US:en', 22:06:37 INFO - 'LOGNAME': 'cltbld', 22:06:37 INFO - 'MAIL': '/var/mail/cltbld', 22:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:37 INFO - 'MOZ_NO_REMOTE': '1', 22:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:37 INFO - 'NO_EM_RESTART': '1', 22:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:37 INFO - 'PWD': '/builds/slave/test', 22:06:37 INFO - 'SHELL': '/bin/bash', 22:06:37 INFO - 'SHLVL': '1', 22:06:37 INFO - 'TERM': 'linux', 22:06:37 INFO - 'TMOUT': '86400', 22:06:37 INFO - 'USER': 'cltbld', 22:06:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:37 INFO - '_': '/tools/buildbot/bin/python'} 22:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 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)) 22:06:37 INFO - Cleaning up... 22:06:37 INFO - Return code: 0 22:06:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:06:37 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')]} 22:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:37 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')]} 22:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:37 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:37 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 22:06:37 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 22:06:37 INFO - Using env: {'DISPLAY': ':0', 22:06:37 INFO - 'HOME': '/home/cltbld', 22:06:37 INFO - 'LANG': 'en_US.UTF-8', 22:06:37 INFO - 'LANGUAGE': 'en_US:en', 22:06:37 INFO - 'LOGNAME': 'cltbld', 22:06:37 INFO - 'MAIL': '/var/mail/cltbld', 22:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:37 INFO - 'MOZ_NO_REMOTE': '1', 22:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:37 INFO - 'NO_EM_RESTART': '1', 22:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:37 INFO - 'PWD': '/builds/slave/test', 22:06:37 INFO - 'SHELL': '/bin/bash', 22:06:37 INFO - 'SHLVL': '1', 22:06:37 INFO - 'TERM': 'linux', 22:06:37 INFO - 'TMOUT': '86400', 22:06:37 INFO - 'USER': 'cltbld', 22:06:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:37 INFO - '_': '/tools/buildbot/bin/python'} 22:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:37 INFO - Downloading/unpacking jsonschema 22:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:06:39 INFO - Downloading/unpacking functools32 (from jsonschema) 22:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:39 INFO - Downloading functools32-3.2.3-2.tar.gz 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:06:39 INFO - warning: no files found matching '*.txt' 22:06:39 INFO - no previously-included directories found matching 'build' 22:06:39 INFO - no previously-included directories found matching 'dist' 22:06:39 INFO - no previously-included directories found matching '.git*' 22:06:39 INFO - Installing collected packages: jsonschema, functools32 22:06:39 INFO - Running setup.py install for functools32 22:06:39 INFO - warning: no files found matching '*.txt' 22:06:39 INFO - no previously-included directories found matching 'build' 22:06:39 INFO - no previously-included directories found matching 'dist' 22:06:39 INFO - no previously-included directories found matching '.git*' 22:06:39 INFO - Successfully installed jsonschema functools32 22:06:39 INFO - Cleaning up... 22:06:39 INFO - Return code: 0 22:06:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:06:39 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')]} 22:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:39 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')]} 22:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:39 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 0x7f4b20acaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc85a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', '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 22:06:39 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 22:06:39 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 22:06:39 INFO - Using env: {'DISPLAY': ':0', 22:06:39 INFO - 'HOME': '/home/cltbld', 22:06:39 INFO - 'LANG': 'en_US.UTF-8', 22:06:39 INFO - 'LANGUAGE': 'en_US:en', 22:06:39 INFO - 'LOGNAME': 'cltbld', 22:06:39 INFO - 'MAIL': '/var/mail/cltbld', 22:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:39 INFO - 'MOZ_NO_REMOTE': '1', 22:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:39 INFO - 'NO_EM_RESTART': '1', 22:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:39 INFO - 'PWD': '/builds/slave/test', 22:06:39 INFO - 'SHELL': '/bin/bash', 22:06:39 INFO - 'SHLVL': '1', 22:06:39 INFO - 'TERM': 'linux', 22:06:39 INFO - 'TMOUT': '86400', 22:06:39 INFO - 'USER': 'cltbld', 22:06:39 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:39 INFO - '_': '/tools/buildbot/bin/python'} 22:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:40 INFO - Downloading/unpacking flake8 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:06:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:06:41 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 22:06:41 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 22:06:41 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 22:06:41 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 22:06:41 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 22:06:41 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 22:06:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:06:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 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 22:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:06:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:06:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:06:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:06:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:06:42 INFO - no previously-included directories found matching 'docs/_build' 22:06:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:06:42 INFO - Running setup.py install for pep8 22:06:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:06:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:06:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:06:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:06:42 INFO - no previously-included directories found matching 'docs/_build' 22:06:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:06:42 INFO - Cleaning up... 22:06:42 INFO - Return code: 0 22:06:42 INFO - Running post-action listener: _resource_record_post_action 22:06:42 INFO - Running post-action listener: _start_resource_monitoring 22:06:42 INFO - Starting resource monitoring. 22:06:42 INFO - ##### 22:06:42 INFO - ##### Running install step. 22:06:42 INFO - ##### 22:06:42 INFO - Running pre-action listener: _resource_record_pre_action 22:06:42 INFO - Running main action method: install 22:06:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:43 INFO - Reading from file tmpfile_stdout 22:06:43 INFO - Detecting whether we're running mozinstall >=1.0... 22:06:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:06:43 INFO - Reading from file tmpfile_stdout 22:06:43 INFO - Output received: 22:06:43 INFO - Usage: mozinstall [options] installer 22:06:43 INFO - Options: 22:06:43 INFO - -h, --help show this help message and exit 22:06:43 INFO - -d DEST, --destination=DEST 22:06:43 INFO - Directory to install application into. [default: 22:06:43 INFO - "/builds/slave/test"] 22:06:43 INFO - --app=APP Application being installed. [default: firefox] 22:06:43 INFO - mkdir: /builds/slave/test/build/application 22:06:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:06:59 INFO - Reading from file tmpfile_stdout 22:06:59 INFO - Output received: 22:06:59 INFO - /builds/slave/test/build/application/firefox/firefox 22:06:59 INFO - Running post-action listener: _resource_record_post_action 22:06:59 INFO - ##### 22:06:59 INFO - ##### Running run-tests step. 22:06:59 INFO - ##### 22:06:59 INFO - Running pre-action listener: _resource_record_pre_action 22:06:59 INFO - Running main action method: run_tests 22:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:06:59 INFO - Python 2.7.3 22:06:59 INFO - Return code: 0 22:06:59 INFO - grabbing minidump binary from tooltool 22:06: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')]} 22:06:59 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 0x2c57410>, '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 0x2c58310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c587a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:06:59 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 22:06:59 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 22:06:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:06:59 INFO - Return code: 0 22:06:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:06:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:06:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:06:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:06:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:07:00 INFO - Return code: 0 22:07:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 22:07:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 22:07:00 INFO - Using env: {'DISPLAY': ':0', 22:07:00 INFO - 'HOME': '/home/cltbld', 22:07:00 INFO - 'LANG': 'en_US.UTF-8', 22:07:00 INFO - 'LANGUAGE': 'en_US:en', 22:07:00 INFO - 'LOGNAME': 'cltbld', 22:07:00 INFO - 'MAIL': '/var/mail/cltbld', 22:07:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:07:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:00 INFO - 'MOZ_NO_REMOTE': '1', 22:07:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:07:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:00 INFO - 'NO_EM_RESTART': '1', 22:07:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:00 INFO - 'PWD': '/builds/slave/test', 22:07:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:07:00 INFO - 'SHELL': '/bin/bash', 22:07:00 INFO - 'SHLVL': '1', 22:07:00 INFO - 'TERM': 'linux', 22:07:00 INFO - 'TMOUT': '86400', 22:07:00 INFO - 'USER': 'cltbld', 22:07:00 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270', 22:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:00 INFO - '_': '/tools/buildbot/bin/python'} 22:07:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 22:07:00 INFO - mozversion application_buildid: 20160425193111 22:07:00 INFO - mozversion application_changeset: cfc7ebe592937ad937cebe04fdad4213eee72fae 22:07:00 INFO - mozversion application_display_name: Nightly 22:07:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:07:00 INFO - mozversion application_name: Firefox 22:07:00 INFO - mozversion application_remotingname: firefox 22:07:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:07:00 INFO - mozversion application_vendor: Mozilla 22:07:00 INFO - mozversion application_version: 49.0a1 22:07:00 INFO - mozversion platform_buildid: 20160425193111 22:07:00 INFO - mozversion platform_changeset: cfc7ebe592937ad937cebe04fdad4213eee72fae 22:07:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:07:00 INFO - mozversion platform_version: 49.0a1 22:07:00 INFO - using testdate: 1461647220 22:07:00 INFO - actual date: 1461647220 22:07:00 INFO - starting webserver on 'localhost:57778' 22:07:00 INFO - SUITE-START | Running 2 tests 22:07:00 INFO - TEST-START | dromaeo_css 22:07:00 INFO - operating with platform_type : linux_ 22:07:00 INFO - Initialising browser for dromaeo_css test... 22:07:00 INFO - TEST-INFO | started process 28704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf5qYoW/profile http://localhost:57778/getInfo.html) 22:07:04 INFO - PROCESS | 28704 | 22:07:04 INFO - PROCESS | 28704 | (process:28775): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:07:05 INFO - PROCESS | 28704 | __metrics Screen width/height:1600/1200 22:07:05 INFO - PROCESS | 28704 | colorDepth:24 22:07:05 INFO - PROCESS | 28704 | Browser inner width/height: 1024/697 22:07:05 INFO - PROCESS | 28704 | __metrics 22:07:06 ERROR - PROCESS | 28704 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 22:07:06 INFO - TEST-INFO | 28704: exit 0 22:07:06 INFO - Browser initialized. 22:07:06 INFO - Running cycle 1/1 for dromaeo_css test... 22:07:06 INFO - TEST-INFO | started process 28826 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf5qYoW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:08 INFO - PROCESS | 28826 | 22:07:08 INFO - PROCESS | 28826 | (process:28887): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:07:18 INFO - PROCESS | 28826 | RSS: Main: 164540416 22:07:18 INFO - PROCESS | 28826 | 22:08:08 INFO - PROCESS | 28826 | 1461647288270 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] 22:10:16 INFO - PROCESS | 28826 | Cycle 1(1): loaded http://localhost:57778/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57778/tests/dromaeo/cssquery-ext.html) 22:10:17 INFO - PROCESS | 28826 | RSS: Main: 169660416 22:10:17 INFO - PROCESS | 28826 | 22:13:19 INFO - PROCESS | 28826 | Cycle 1(1): loaded http://localhost:57778/tests/dromaeo/cssquery-ext.html (next: http://localhost:57778/tests/dromaeo/cssquery-jquery.html) 22:13:20 INFO - PROCESS | 28826 | RSS: Main: 166744064 22:13:20 INFO - PROCESS | 28826 | 22:16:21 INFO - PROCESS | 28826 | Cycle 1(1): loaded http://localhost:57778/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57778/tests/dromaeo/cssquery-mootools.html) 22:16:22 INFO - PROCESS | 28826 | RSS: Main: 168099840 22:16:22 INFO - PROCESS | 28826 | 22:19:24 INFO - PROCESS | 28826 | Cycle 1(1): loaded http://localhost:57778/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57778/tests/dromaeo/cssquery-prototype.html) 22:19:24 INFO - PROCESS | 28826 | RSS: Main: 165892096 22:19:24 INFO - PROCESS | 28826 | 22:22:27 INFO - PROCESS | 28826 | Cycle 1(1): loaded http://localhost:57778/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57778/tests/dromaeo/cssquery-yui.html) 22:22:27 INFO - PROCESS | 28826 | RSS: Main: 166846464 22:22:27 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | Cycle 1(1): loaded http://localhost:57778/tests/dromaeo/cssquery-yui.html (next: http://localhost:57778/tests/dromaeo/cssquery-dojo.html) 22:25:30 INFO - PROCESS | 28826 | RSS: Main: 169295872 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | __start_tp_report 22:25:30 INFO - PROCESS | 28826 | _x_x_mozilla_page_load 22:25:30 INFO - PROCESS | 28826 | _x_x_mozilla_page_load_details 22:25:30 INFO - PROCESS | 28826 | |i|pagename|runs| 22:25:30 INFO - PROCESS | 28826 | |0;dojo.html;3136.2473006478444;3173.365326934611;3198.4402729522335;3211.807291562508;3217.9195520111994;5449.4005659377435;5450.56395488361;5452.6455780374345;5453.154761012044;5492.313460817398;5301.734913254331;5373.382061062979;5397.514223719864;5399.136138217886;5557.972210138953;4838.419389673244;4882.902341953159;4931.630127740415;4957.3059771632;4961.677490963076;880.7660974222608;4791.880202994918;4925.802967881281;4932.53140951609;5004.174311238641;4878.390201224847;4932.950670493303;4948.430930443009;4949.331840201581;4973.900521989558;4902.534259245366;4931.087748766454;4935.876603084916;4962.1812400953795;4990.201567749143;4849.660523763338;4881.780319885614;4892.0705066037335;4893.828715994923;4912.7297998610065;5308.191019130799;5313.07021271276;5359.981603495324;5379.1662292344745;5397.62216644835;5642.689652069134;5714.485696287354;5816.82549523515;5821.3014438267255;5825.766969321222;5806.535477161817;5812.651240925558;5834.5915785895;5837.29952405714;5892.469677729025;5422.75597598109;5427.972860135714;5434.701091439969;5435.320584926884;5436.7553460094;5834.416558344132;5892.469677728939;5904.9704751475965;5974.940250597525;6066.059760737093;5728.427157284238;5757.165211044375;5761.596688231783;5781.161731548902;5812.651240925558;5767.942320576741;5809.24479817621;5812.738426770806;5823.417658234142;5828.12578113278;5627.409122042209;5698.515626171739;5746.224259724883;5751.079827227707;5775.104858746817;5752.453516915886;5770.711464426762;5773.88452230953;5780.768769249183;5814.476697097281;4259.339802330644;4340.631046361032;4384.605385515255;4387.6270517006415;4418.889527761831;3842.442845787345;3897.57126716061;3932;3951.091249012743;3974.125692347679;5547.889042219133;5598;5606.21512988182;5641.717914104278;5667.603267771299;5681.289838770153;5814.156947242626;5820.505257053198;5849.64902105875;5874.618149820248;5529.4470552944385;5590.133529302964;5592.888142237214;5639.464250896158;5650.039493286151;4949.7030178189425;4969.801207951641;4976.104301225741;4983.103041452501;5039.974800125976;5952.672603006876;5968.910466343008;5978.880422391528;6014.248218972629;6022.337542870281;5809.709514524172;5816.883662326645;5822.854428639318;5831.854203644943;5838.503727183237;5967.6419414835245;6003.249593800775;6004.099385092271;6007.969960150346;6016.849578760566;5610.354809196994;5685.374608793112;5731.598788084794;5736.942630573641;5748.396418376177;5758.395368486249;5815.244018277766;5854.2389489366105;5863.589548731718;5900;5661.858453538694;5662.801801936913;5666.2350582670815;5672.319321681424;5674.517665998436;5493.478119578634;5654.717264136859;5657.179708942263;5732.71336433185;5767.048437007847;5601.103823388238;5619.269494965628;5627.915581266366;5638.37977822431;5651.971740141437;5689.687067211343;5765.336986246635;5767.596268261182;5767.68277744728;5810.94189058104;5528.308961379827;5616.157576363578;5694.231278777312;5706.8573285666525;5718.428157184248 22:25:30 INFO - PROCESS | 28826 | |1;ext.html;591.8697886464979;628.095542418917;636.7421194416262;656.022526435611;676.5061505101273;38073.42889856651;40118.19763604726;40145.79927100364;40197.19605607886;40311;40530;40674.389884151686;41048.794756026255;41118.794406027926;41375.58624413755;8322.916770832306;8656.956715216447;8666.30669546438;8682.26200772932;8687.95656021719;8484.830303393928;8760.561971901412;8839.204471597555;8844.027157012724;8854.07032261613;8817.823643527126;8932.464052156858;8935.821283574325;8950.686725964593;8963.686270980517;8817.294616430701;8903.510306933149;8918.687845925426;8932.151445612657;8936.32977526688;8786.297096232285;8802.559872006437;8866.246369058637;8934.240589549896;8936.285097192209;28093.438131237464;40944.181116377506;41153.58846411527;41169.794151029055;41460.1707965842;3415.79505229687;3438.8796392126405;3511;3582.8925132245813;3591.2099338145567;28294.717052829415;28656.426871462452;28820.567691484644;28963.13110606664;29094.27264318409;3959.7426167299323;4032.858849940238;4119.464469618931;4180.874573762761;4353.085851971058;272556;276101.6194919033;276166;277666;279097;56609.71695141498;57117;57243.71378143083;57376;57517.712411438515;55006.44993550093;56404.71797641068;56526.43473565294;56757.7162114195;59572.702136489905;16013.919930400274;16136.273867675984;16144.112073835817;16162.191890405433;16230.269637866088;12643.683907902192;12757.298348590917;12762.489500419879;12782.68043298925;12804.61586152417;19411.417657470298;19738.901305493382;19760.60478790445;19844.206231750857;19920.50198745043;7123.501354905107;7208.96395518019;7240.298529845482;7342.559446433231;7404.148522919825;1053.7154968158554;1066.4347895615335;1088.9564417423214;1105.8285965675334;1111.349860331699;30942.590226764667;31350.686493135232;31537.52693709596;31606.367872642877;31707.36585268282;1125.983110253347;1136.8294755786699;1140.0537553830302;1173.6185739634652;1193.5404869125275;16506.75239871403;16706.331746730237;16861.325546978227;16929.746053809202;17247.81788216319;545666;548999;550853;550941;557854;57441.13838292429;57924.710376447845;57978.13032804511;58120;58207.41792582105;1337.284552764286;1350.0549615269351;1365.938532766028;1370.280602683599;1379.1518216296904;30554;31096.378072438878;31297.687023129478;31674;32203.838980804947;43308.78345608252;47307.763461182476;49035.774105647644;49120.2631960528;49283.75358123187;359032;362238;363583;365347;367295;39923.40114898337;39968.80015599904;40103.59896400999;40259.79870100631;40371.79814100911;44671.10657786891;44957;45069.54930450654;45518.317225240986;45821.77089114533;39601.52674806631;39811.80094099627;40315.79842100888;40327.193456130124;41165.17669646531;38828.22343553056;39971.40042899301;40596.594034060465;40729.38905916473;41137;41051;41374.37938430866;41993.58006419897;42178.36732448954;42475.787621061696;34042.31915361728;36292.81853590715;36447.45328820123;36571.26857462889;36692.63307366892;28271.2932176689;28690;28849.278768030967;28953.276168095967;28993 22:25:30 INFO - PROCESS | 28826 | |2;jquery.html;377.64312724475366;382.5103867050176;383.38466760848837;392.84678975199677;394.9229900169468;15092.773608395883;16830.915845420757;17313.307467701292;19758.506037349056;20191.798082019177;17698.82301176991;18069.4579162625;18417.539561510934;18548.90725546374;18574.81425185748;5544.528715059215;5664.575156863231;5679.4036626154275;5706.800261990831;5709.371969083398;5028.647994640377;5269.6311258211945;5292.126799078147;5303.681779093247;5353.678779273237;5254.343207099113;5254.658447200939;5256;5339.6262261641705;6170.382961703838;6154.784582539634;6169.043798211284;6182.258129024523;6186.505079593621;6206.472449841792;5237.685738855643;5340.465953404629;5357.946420535784;5358.383785864637;5535.335759708861;21700.34898953034;22232;22415.21546745856;22516.662250066263;22603.287605487447;2191.1345018717516;2303.343547089088;2386.9880650596638;2496.450780828215;2515.748425157488;9781.46201958892;10163.796724065478;14362.928185359006;14443.711125777425;14541.927290363587;1938.0987840654022;2017.1427143463968;2031.6343058249508;2035.8982050897544;2047.8668886522328;82343.5882820588;86478;87517.12482875153;88189;88505.55747221287;10482.37105773656;10507.684769456928;10724.14206863449;10772.515236814363;10982.395968221665;15079.849201507845;15262.16058116792;15338.846611533741;15349.923250383677;15350.692986140215;7824.139344672081;8197.590120493951;8248.340132789483;8368;8369.581520924052;7922.722704705423;7926.365890728728;8010.118886922434;8043.436959412902;8140.470869393471;4069.613386728315;4571.519990401027;4576.633869290449;4597.103564804898;4680.812767489262;1826.678837910786;1911.388355726182;1912.1490936533387;1912.3880358285494;1931.9613607727767;890.973270801877;988.9159421449258;1020.4285600063988;1045.440689231273;1068.3483075324045;18189.818101819077;18585.81414185868;18849.528761781065;18956.715649265272;19356.32252871143;923.9076092390842;951.2437612098398;962.764122789921;973.7419583810296;979.407458487619;10350.741231469123;10710.410927398912;10744.301620394806;10764;10815.134789217002;511148;514849;516507;520294;529385;92358.53820730853;92386;94661;95072;96824.51587742016;992.8212921674024;1007.8941711120227;1018.872640919885;1028.5885645741614;1034.0538407357335;16481.17594120313;17051.48845534636;17110.486685399457;17250.568735781704;17407.82592174062;38939;39860.80069599633;40234.79882600568;40597.79701101594;41558;56132.43867561272;56410.43589564052;56550.71724641351;56866.715666421405;57125.42874571201;8046.798830029296;8382.455140415854;8399.95800020996;8524.872126917977;8616.310695144248;9313.161815436642;11589.478473468545;12767.361631918553;12872.292023938773;13066.150700204456;11453.713657158305;11733.530658773894;11971.940140299592;12134.635960921187;12193.207441516273;11504;11573.710657233636;11662.241954272946;11666;12111.878881211074;12069;12111.878881211074;12191.573294934635;12529.561465349034;12832.55086071983;8536.829263414822;8576.742697719079;8676.305895528216;8692.130786921258;8861.468311901306;10806.891931080589;11157.776844463227;11527.711807204887;11572.24780389272;11601.767964640829 22:25:30 INFO - PROCESS | 28826 | |3;mootools.html;1448.5219877440443;1470.1620076556362;1579.2261791722053;1723.6035711786296;1761.2690733345655;7586.013818203626;8055.476394034384;8126.390520710948;8446.15538446155;8483.533405662694;8056.798580035487;8088.91911080889;8104.756857294291;8143;8201.179882011797;2244.0911430870524;2271.318604418676;2280.7149106361703;2290.1297506947335;2290.2442194075916;2244.674522194281;2261.502469456725;2311.953760924789;2312.0520586559514;2316.247219653611;2240.215924426446;2270.761570035147;2287.874166920819;2346.5189636124555;2347.3427440316736;2241.316398498457;2256.729192496911;2264.467850055236;2264.558411109834;2265.139247086105;2240.8095311898346;2251.9324420267417;2265.5468906218657;2291.51878105598;2323.7560056194025;8258.42191046627;8320.958395208047;8322.375821813328;8381.916180838236;8383.16168383163;4964.826231081931;5158.2520534522655;5187.403448603422;5213.218017297436;5228.398734145584;3057.9235519111976;3214.758893083028;3356.848941797625;3377.5778027746533;3383.0527452313145;1056.6988408303673;1068.2628985999636;1092.3500517192258;1108.927919685226;1109.6837401340579;11525.884741152566;11553.826692599618;11564.999999999916;11589.072874170046;11592.710182245428;4478.283474644107;4482.394876691605;4647.047355292157;4653.139169253699;4699.765011749399;9811.460369679593;9814.852777208205;9815.558299876522;9822.361546499455;9864.654737084167;5293.57651387888;5298.788048478018;5308.575313974873;5312.601554883322;5312.76092575835;4318.093200427946;4478.910421791546;4694.6713730039255;4760.285957106462;4891.119598472238;3791.109089363997;3820.4842346283453;3923.725339226227;3929.8035098244973;3984.143409167043;1256.0830593666674;1261.873812618749;1268.4355461819403;1275.6300672804969;1306.5884246462333;903.074348792493;929.1034152043311;960.3325688646323;962.4562122400821;968.651285537206;5340.225667278223;5445.50990410865;5498.45015498455;5731.684757338387;5769.9711501442225;418.44764910318696;423.2825361013053;435.46437881406047;435.71460693245973;449.3327408797932;3966.5636779954184;3996.660283875845;4001.519817621904;4016.5180178378782;4086.7752273625238;10113;10331.380117192992;10415.906329835258;10558.894411055944;10568.260221784401;10262.743431414274;10303.021212984755;10354.378737275636;10436.060754531976;10467.110295624958;7519.436042296849;7882.448228624056;7904.604769761489;7905;8053.033635963722;5786.132080187837;5806.129080637938;5905.4685078343155;5936.990711579128;5954.851128721643;9024.36829421934;9530.142287194185;9576.425414475154;9640.90359096428;9715.70852874415;9349.813003739751;9356.625734970525;9373.484458354857;9391.436513809193;9466.148046675833;6928.272531384132;7182.640867956686;7439.921211424421;7445.218252083453;7622;7939.603019848869;7970.043594768665;7982.08206056311;8013.6794528218215;8017.559034253172;7113.430925526049;7288.672009759467;7392.630368481447;7400.074990626172;7434.1450733166375;7191.532550384209;7239.529430586995;7246.4202863771825;7259.963700181465;7304.160021597584;6604.174478190226;6786.69459874297;6806.489513286424;6832.111825462658;6835.316468353125;4825.5174482551465;4865.683730557503;4889.584385327287;5190.091733946469;5267.499587539178;5158.509941555546;5210.53105220532;5220.504052115043;5239.083160446983;5289.418164002033 22:25:30 INFO - PROCESS | 28826 | |4;prototype.html;315.43851943540494;322.5532637297343;323.8882585507999;328.89146581628063;329.0326440265619;5390.51485366317;5431.592630552705;5474.507294343508;5539.639923404976;5596.972015139923;5481.5340696040885;5498.395176530588;5502.147167189092;5515;5517.84803926469;4281.5290318065145;4283.935740963888;4309.224339618867;4324.243257429937;4327.783610819462;4214.641755450775;4257.020485474254;4265.914681706379;4268.167707297078;4291.8497852575165;4193.643540299078;4210.97894510527;4225.91548169038;4243.072280126718;4251.638610718092;4219.135077309175;4258.808353624064;4262.147570485899;4283.657307415399;4292.356146578023;3702.7037836972977;3761.191343861083;3775.1883345080664;3787.791671458069;3821.0638393593467;5275.28783614211;5299.4700529946695;5321.6540924839765;5340.62615616907;5343.599230057723;4851.830185943475;4899.216125419915;4908.680935739166;4934.481879402683;4959.70241785494;5607.943920560824;5739.282589676291;5743.971280143573;5773.278340207474;5778;4115.34154535276;4136.958630413687;4162.354835000579;4175.394567787654;4179.247735407625;5987.970060149715;6016.909746353808;6022.175918291872;6045.697715114226;6066.787662431793;4049.8785036448953;4136.21411931738;4151.958480415157;4166.354215096665;4182.707210495297;4256.893577660521;4274.883932453456;4275.178382267688;4300.268954277781;4308.18144552534;4885.560299572984;4887.608991280689;4899.412070551485;4927.063857866994;4943;5755.913661295084;5828.854278643068;5872;5922.3485416604135;5930.34766175729;1552.0842702805398;1561.3130222701975;1564.4915402494235;1567.902468272214;1571.4107209796327;1032.736652153696;1043.5773511727762;1044.6865940217904;1045.968620941373;1054.8154073037342;5957.136215248851;5968.8806223876145;5974.163617093611;5986.2517185351835;5987.43119403656;6366.840828979312;6371.203599550056;6375.489960803125;6418.390252925965;6420.7431702731365;5814.738336774855;5965.254343207099;5999.250093738283;5999.730012149464;6014.729337179838;3394.405978953673;3396.337714145767;3400.6599340065795;3410.914727131792;3428.211511352403;6490.870182596416;6544.476441884638;6594.274629790719;6654.234763002317;6659.367360100783;6482.67586620667;6585.440237579764;6586.571872828251;6605.5376123670885;6614.7023383947835;5849.64902105875;6216.533759968021;6592.604443733391;6600.273969863311;6646.767363142267;5985.431384018511;5995.770866972348;6006.87986240264;6013.458788709037;6046.486048685911;5976.820695379146;5980;5989.490893274121;5993.760249589967;5998.760049598141;5930.614510057005;6233.937660623335;6237.625742455467;6253.843653908689;6259.593126446766;3937.8621748238675;4049.9392509112977;4182.351735481006;4188.308929026676;4195.964956995916;5881.470667639762;5938.524918006463;5960.688648497324;5964.850878728066;5975.16347711312;5728.312602487562;5784.21913041734;5829.8251052468495;5847.502962248256;5870.706464676835;5794.507466865363;5815.302163740378;5825.2135961644635;5845.3570107289015;5858.619189752652;5763.279590051244;5764.204360885051;5767.048437007847;5771.691323470645;5771.903338365697;5583.860403489945;5870.412958704095;5876.559258055749;5881.088431293157;5905.261842269716;5628.60599758013;5702.486776190163;5711.400302968294;5732.226149469935;5758.740856661377 22:25:30 INFO - PROCESS | 28826 | |5;yui.html;787.8424315136972;811.1280373598381;837.7989282572184;840.1640367834007;852.3011130872679;13682.657933551665;14096;14141.71716565668;14347.21090340031;14353.856461435369;12321.876781232186;12341.568045118423;12446.813297800518;12798.552050678229;12884.871151288484;674.9898751518731;784.7253461288532;785.1363500149847;792.7502836606451;795.7413840501831;796.8326651403212;800.1918062756629;802.8033131882697;805.375833728859;806.1535387842771;841.9915800842005;845.2899564365931;845.4631309118723;845.7335939179139;850.3622283287534;843.8312337532487;843.9956451822312;845.488479469918;847.9533625650648;851.4423052900329;617.0158597037718;619.5198720991223;620.8447888027993;620.9254889413254;621.8103478439074;14284.500042498463;14326.140431574035;14381.640458988504;14445.566633001026;14448.494302699355;14288.285585720776;14409.639759006004;14467.602382243662;14535.200563968978;14565.708685826225;10926.672199834018;10982.286151400212;11087.944560277228;11096;11275.267107637977;3867.16855875988;3874.8062596870327;3881.4954055972826;4039.1921615676983;4063.106116654361;55819.44180558224;56280;56408;56487.435125648626;56640.43359566434;11263.605773797877;11289.379084150287;11354.091672666167;11361.14791390649;11690.240134391237;20336.69494957577;20445.079971401323;20451.693224601644;20454.795452045586;20470.488237793877;8067.515949043076;8168.550729709923;8224.300934420522;8258.42191046633;8503.362247831437;4621.95378046222;4624.491305956342;4681.976590117096;4771.069641419959;4794.448638406628;3290.0952238134228;3364.444866597033;3365.7139143172617;3374.8481318340732;3384.1370450535446;548.1667864845413;548.1667864845413;551.9061759500895;556.8802707417925;577.1458241802072;628.5348841856993;725.1624373848214;725.2928394815013;756.9432292578078;765.6745882999703;8511.106333834858;8710.085441028728;8814.427062240933;8933.106689331145;8941.910580894239;639.5139693832627;680.7719413996367;723.3200791256307;731.6670914733784;738.1732459645232;4339.088791353788;4373.518912919576;4387.122575484916;4387.890302742393;4404.867853964386;64502.26187798393;65134;66215.33784662189;66655.66672166609;66686.33313666898;58504.41495585075;58522.4147758517;59721.70139149277;60637;61979.070313945325;54386.728066359414;55327.72336138374;55909.16136258041;55964;56064.71967640136;8471.745847624581;8596.828063438572;8714.259287960595;8736.958097148017;8779.780505487159;36851.447228292134;37202.25595488129;37346.626533734314;37547.62452375441;38454.2309153821;47731.52268477271;48419;48489;49971.50028499814;50812.49187508078;11187.32876027441;11295.604653837076;11728.41357932117;12189.573364932185;12633.557825476064;11972.401379930794;12003.699907502032;12391.132620716464;12517.687057823627;12974.545890893773;11987.34069626179;12047.216930899463;12073.094517911368;12082.758344832879;12322.630321090382;11181.776364472827;11266.718332041764;11880.643580692593;11897.821532676844;11984.700382490508;10523.526441310312;10919.962603552649;11649.242799218024;11798.823017654913;11836.349000804694;8951.149640784115;9486.905130948879;9661.613535458784;9675.516224188903;9973.800523989334;9314.580843862179;10059.49702514886;10352.17182625373;10397.688069357931;10709.410982396043 22:25:30 INFO - PROCESS | 28826 | __end_tp_report 22:25:30 INFO - PROCESS | 28826 | __start_cc_report 22:25:30 INFO - PROCESS | 28826 | _x_x_mozilla_cycle_collect,329 22:25:30 INFO - PROCESS | 28826 | __end_cc_report 22:25:30 INFO - PROCESS | 28826 | __startTimestamp1461648330369__endTimestamp 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | ------- Summary: start ------- 22:25:30 INFO - PROCESS | 28826 | Number of tests: 6 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | [#0] dojo.html Cycles:165 Average:5370.86 Median:5650.04 stddev:710.43 (12.6%) stddev-sans-first:690.65 22:25:30 INFO - PROCESS | 28826 | Values: 3136.2 3173.4 3198.4 3211.8 3217.9 5449.4 5450.6 5452.6 5453.2 5492.3 5301.7 5373.4 5397.5 5399.1 5558.0 4838.4 4882.9 4931.6 4957.3 4961.7 880.8 4791.9 4925.8 4932.5 5004.2 4878.4 4933.0 4948.4 4949.3 4973.9 4902.5 4931.1 4935.9 4962.2 4990.2 4849.7 4881.8 4892.1 4893.8 4912.7 5308.2 5313.1 5360.0 5379.2 5397.6 5642.7 5714.5 5816.8 5821.3 5825.8 5806.5 5812.7 5834.6 5837.3 5892.5 5422.8 5428.0 5434.7 5435.3 5436.8 5834.4 5892.5 5905.0 5974.9 6066.1 5728.4 5757.2 5761.6 5781.2 5812.7 5767.9 5809.2 5812.7 5823.4 5828.1 5627.4 5698.5 5746.2 5751.1 5775.1 5752.5 5770.7 5773.9 5780.8 5814.5 4259.3 4340.6 4384.6 4387.6 4418.9 3842.4 3897.6 3932.0 3951.1 3974.1 5547.9 5598.0 5606.2 5641.7 5667.6 5681.3 5814.2 5820.5 5849.6 5874.6 5529.4 5590.1 5592.9 5639.5 5650.0 4949.7 4969.8 4976.1 4983.1 5040.0 5952.7 5968.9 5978.9 6014.2 6022.3 5809.7 5816.9 5822.9 5831.9 5838.5 5967.6 6003.2 6004.1 6008.0 6016.8 5610.4 5685.4 5731.6 5736.9 5748.4 5758.4 5815.2 5854.2 5863.6 5900.0 5661.9 5662.8 5666.2 5672.3 5674.5 5493.5 5654.7 5657.2 5732.7 5767.0 5601.1 5619.3 5627.9 5638.4 5652.0 5689.7 5765.3 5767.6 5767.7 5810.9 5528.3 5616.2 5694.2 5706.9 5718.4 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | [#1] ext.html Cycles:180 Average:56171.03 Median:22174.33 stddev:109648.86 (494.5%) stddev-sans-first:109877.05 22:25:30 INFO - PROCESS | 28826 | Values: 591.9 628.1 636.7 656.0 676.5 38073.4 40118.2 40145.8 40197.2 40311.0 40530.0 40674.4 41048.8 41118.8 41375.6 8322.9 8657.0 8666.3 8682.3 8688.0 8484.8 8760.6 8839.2 8844.0 8854.1 8817.8 8932.5 8935.8 8950.7 8963.7 8817.3 8903.5 8918.7 8932.2 8936.3 8786.3 8802.6 8866.2 8934.2 8936.3 28093.4 40944.2 41153.6 41169.8 41460.2 3415.8 3438.9 3511.0 3582.9 3591.2 28294.7 28656.4 28820.6 28963.1 29094.3 3959.7 4032.9 4119.5 4180.9 4353.1 272556.0 276101.6 276166.0 277666.0 279097.0 56609.7 57117.0 57243.7 57376.0 57517.7 55006.4 56404.7 56526.4 56757.7 59572.7 16013.9 16136.3 16144.1 16162.2 16230.3 12643.7 12757.3 12762.5 12782.7 12804.6 19411.4 19738.9 19760.6 19844.2 19920.5 7123.5 7209.0 7240.3 7342.6 7404.1 1053.7 1066.4 1089.0 1105.8 1111.3 30942.6 31350.7 31537.5 31606.4 31707.4 1126.0 1136.8 1140.1 1173.6 1193.5 16506.8 16706.3 16861.3 16929.7 17247.8 545666.0 548999.0 550853.0 550941.0 557854.0 57441.1 57924.7 57978.1 58120.0 58207.4 1337.3 1350.1 1365.9 1370.3 1379.2 30554.0 31096.4 31297.7 31674.0 32203.8 43308.8 47307.8 49035.8 49120.3 49283.8 359032.0 362238.0 363583.0 365347.0 367295.0 39923.4 39968.8 40103.6 40259.8 40371.8 44671.1 44957.0 45069.5 45518.3 45821.8 39601.5 39811.8 40315.8 40327.2 41165.2 38828.2 39971.4 40596.6 40729.4 41137.0 41051.0 41374.4 41993.6 42178.4 42475.8 34042.3 36292.8 36447.5 36571.3 36692.6 28271.3 28690.0 28849.3 28953.3 28993.0 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | [#2] jquery.html Cycles:180 Average:29840.20 Median:380.08 stddev:85478.65 (22489.8%) stddev-sans-first:85689.82 22:25:30 INFO - PROCESS | 28826 | Values: 377.6 382.5 383.4 392.8 394.9 15092.8 16830.9 17313.3 19758.5 20191.8 17698.8 18069.5 18417.5 18548.9 18574.8 5544.5 5664.6 5679.4 5706.8 5709.4 5028.6 5269.6 5292.1 5303.7 5353.7 5254.3 5254.7 5256.0 5339.6 6170.4 6154.8 6169.0 6182.3 6186.5 6206.5 5237.7 5340.5 5357.9 5358.4 5535.3 21700.3 22232.0 22415.2 22516.7 22603.3 2191.1 2303.3 2387.0 2496.5 2515.7 9781.5 10163.8 14362.9 14443.7 14541.9 1938.1 2017.1 2031.6 2035.9 2047.9 82343.6 86478.0 87517.1 88189.0 88505.6 10482.4 10507.7 10724.1 10772.5 10982.4 15079.8 15262.2 15338.8 15349.9 15350.7 7824.1 8197.6 8248.3 8368.0 8369.6 7922.7 7926.4 8010.1 8043.4 8140.5 4069.6 4571.5 4576.6 4597.1 4680.8 1826.7 1911.4 1912.1 1912.4 1932.0 891.0 988.9 1020.4 1045.4 1068.3 18189.8 18585.8 18849.5 18956.7 19356.3 923.9 951.2 962.8 973.7 979.4 10350.7 10710.4 10744.3 10764.0 10815.1 511148.0 514849.0 516507.0 520294.0 529385.0 92358.5 92386.0 94661.0 95072.0 96824.5 992.8 1007.9 1018.9 1028.6 1034.1 16481.2 17051.5 17110.5 17250.6 17407.8 38939.0 39860.8 40234.8 40597.8 41558.0 56132.4 56410.4 56550.7 56866.7 57125.4 8046.8 8382.5 8400.0 8524.9 8616.3 9313.2 11589.5 12767.4 12872.3 13066.2 11453.7 11733.5 11971.9 12134.6 12193.2 11504.0 11573.7 11662.2 11666.0 12111.9 12069.0 12111.9 12191.6 12529.6 12832.6 8536.8 8576.7 8676.3 8692.1 8861.5 10806.9 11157.8 11527.7 11572.2 11601.8 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | [#3] mootools.html Cycles:180 Average:5538.71 Median:5172.96 stddev:3092.60 (59.8%) stddev-sans-first:3086.00 22:25:30 INFO - PROCESS | 28826 | Values: 1448.5 1470.2 1579.2 1723.6 1761.3 7586.0 8055.5 8126.4 8446.2 8483.5 8056.8 8088.9 8104.8 8143.0 8201.2 2244.1 2271.3 2280.7 2290.1 2290.2 2244.7 2261.5 2312.0 2312.1 2316.2 2240.2 2270.8 2287.9 2346.5 2347.3 2241.3 2256.7 2264.5 2264.6 2265.1 2240.8 2251.9 2265.5 2291.5 2323.8 8258.4 8321.0 8322.4 8381.9 8383.2 4964.8 5158.3 5187.4 5213.2 5228.4 3057.9 3214.8 3356.8 3377.6 3383.1 1056.7 1068.3 1092.4 1108.9 1109.7 11525.9 11553.8 11565.0 11589.1 11592.7 4478.3 4482.4 4647.0 4653.1 4699.8 9811.5 9814.9 9815.6 9822.4 9864.7 5293.6 5298.8 5308.6 5312.6 5312.8 4318.1 4478.9 4694.7 4760.3 4891.1 3791.1 3820.5 3923.7 3929.8 3984.1 1256.1 1261.9 1268.4 1275.6 1306.6 903.1 929.1 960.3 962.5 968.7 5340.2 5445.5 5498.5 5731.7 5770.0 418.4 423.3 435.5 435.7 449.3 3966.6 3996.7 4001.5 4016.5 4086.8 10113.0 10331.4 10415.9 10558.9 10568.3 10262.7 10303.0 10354.4 10436.1 10467.1 7519.4 7882.4 7904.6 7905.0 8053.0 5786.1 5806.1 5905.5 5937.0 5954.9 9024.4 9530.1 9576.4 9640.9 9715.7 9349.8 9356.6 9373.5 9391.4 9466.1 6928.3 7182.6 7439.9 7445.2 7622.0 7939.6 7970.0 7982.1 8013.7 8017.6 7113.4 7288.7 7392.6 7400.1 7434.1 7191.5 7239.5 7246.4 7260.0 7304.2 6604.2 6786.7 6806.5 6832.1 6835.3 4825.5 4865.7 4889.6 5190.1 5267.5 5158.5 5210.5 5220.5 5239.1 5289.4 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | [#4] prototype.html Cycles:180 Average:4958.90 Median:5602.46 stddev:1490.02 (26.6%) stddev-sans-first:1452.87 22:25:30 INFO - PROCESS | 28826 | Values: 315.4 322.6 323.9 328.9 329.0 5390.5 5431.6 5474.5 5539.6 5597.0 5481.5 5498.4 5502.1 5515.0 5517.8 4281.5 4283.9 4309.2 4324.2 4327.8 4214.6 4257.0 4265.9 4268.2 4291.8 4193.6 4211.0 4225.9 4243.1 4251.6 4219.1 4258.8 4262.1 4283.7 4292.4 3702.7 3761.2 3775.2 3787.8 3821.1 5275.3 5299.5 5321.7 5340.6 5343.6 4851.8 4899.2 4908.7 4934.5 4959.7 5607.9 5739.3 5744.0 5773.3 5778.0 4115.3 4137.0 4162.4 4175.4 4179.2 5988.0 6016.9 6022.2 6045.7 6066.8 4049.9 4136.2 4152.0 4166.4 4182.7 4256.9 4274.9 4275.2 4300.3 4308.2 4885.6 4887.6 4899.4 4927.1 4943.0 5755.9 5828.9 5872.0 5922.3 5930.3 1552.1 1561.3 1564.5 1567.9 1571.4 1032.7 1043.6 1044.7 1046.0 1054.8 5957.1 5968.9 5974.2 5986.3 5987.4 6366.8 6371.2 6375.5 6418.4 6420.7 5814.7 5965.3 5999.3 5999.7 6014.7 3394.4 3396.3 3400.7 3410.9 3428.2 6490.9 6544.5 6594.3 6654.2 6659.4 6482.7 6585.4 6586.6 6605.5 6614.7 5849.6 6216.5 6592.6 6600.3 6646.8 5985.4 5995.8 6006.9 6013.5 6046.5 5976.8 5980.0 5989.5 5993.8 5998.8 5930.6 6233.9 6237.6 6253.8 6259.6 3937.9 4049.9 4182.4 4188.3 4196.0 5881.5 5938.5 5960.7 5964.9 5975.2 5728.3 5784.2 5829.8 5847.5 5870.7 5794.5 5815.3 5825.2 5845.4 5858.6 5763.3 5764.2 5767.0 5771.7 5771.9 5583.9 5870.4 5876.6 5881.1 5905.3 5628.6 5702.5 5711.4 5732.2 5758.7 22:25:30 INFO - PROCESS | 28826 | 22:25:30 INFO - PROCESS | 28826 | [#5] yui.html Cycles:180 Average:15285.61 Median:48454.00 stddev:18474.05 (38.1%) stddev-sans-first:18493.80 22:25:30 INFO - PROCESS | 28826 | Values: 787.8 811.1 837.8 840.2 852.3 13682.7 14096.0 14141.7 14347.2 14353.9 12321.9 12341.6 12446.8 12798.6 12884.9 675.0 784.7 785.1 792.8 795.7 796.8 800.2 802.8 805.4 806.2 842.0 845.3 845.5 845.7 850.4 843.8 844.0 845.5 848.0 851.4 617.0 619.5 620.8 620.9 621.8 14284.5 14326.1 14381.6 14445.6 14448.5 14288.3 14409.6 14467.6 14535.2 14565.7 10926.7 10982.3 11087.9 11096.0 11275.3 3867.2 3874.8 3881.5 4039.2 4063.1 55819.4 56280.0 56408.0 56487.4 56640.4 11263.6 11289.4 11354.1 11361.1 11690.2 20336.7 20445.1 20451.7 20454.8 20470.5 8067.5 8168.6 8224.3 8258.4 8503.4 4622.0 4624.5 4682.0 4771.1 4794.4 3290.1 3364.4 3365.7 3374.8 3384.1 548.2 548.2 551.9 556.9 577.1 628.5 725.2 725.3 756.9 765.7 8511.1 8710.1 8814.4 8933.1 8941.9 639.5 680.8 723.3 731.7 738.2 4339.1 4373.5 4387.1 4387.9 4404.9 64502.3 65134.0 66215.3 66655.7 66686.3 58504.4 58522.4 59721.7 60637.0 61979.1 54386.7 55327.7 55909.2 55964.0 56064.7 8471.7 8596.8 8714.3 8737.0 8779.8 36851.4 37202.3 37346.6 37547.6 38454.2 47731.5 48419.0 48489.0 49971.5 50812.5 11187.3 11295.6 11728.4 12189.6 12633.6 11972.4 12003.7 12391.1 12517.7 12974.5 11987.3 12047.2 12073.1 12082.8 12322.6 11181.8 11266.7 11880.6 11897.8 11984.7 10523.5 10920.0 11649.2 11798.8 11836.3 8951.1 9486.9 9661.6 9675.5 9973.8 9314.6 10059.5 10352.2 10397.7 10709.4 22:25:30 INFO - PROCESS | 28826 | -------- Summary: end -------- 22:25:30 INFO - PROCESS | 28826 | 22:25:31 INFO - TEST-INFO | 28826: exit 0 22:25:31 INFO - TEST-OK | dromaeo_css | took 1110125ms 22:25:31 INFO - TEST-START | kraken 22:25:31 INFO - operating with platform_type : linux_ 22:25:31 INFO - Initialising browser for kraken test... 22:25:31 INFO - TEST-INFO | started process 29062 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuFl4Xq/profile http://localhost:57778/getInfo.html) 22:25:34 INFO - PROCESS | 29062 | 22:25:34 INFO - PROCESS | 29062 | (process:29127): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:25:35 INFO - PROCESS | 29062 | __metrics Screen width/height:1600/1200 22:25:35 INFO - PROCESS | 29062 | colorDepth:24 22:25:35 INFO - PROCESS | 29062 | Browser inner width/height: 1024/697 22:25:35 INFO - PROCESS | 29062 | __metrics 22:25:35 ERROR - PROCESS | 29062 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 22:25:36 INFO - TEST-INFO | 29062: exit 0 22:25:36 INFO - Browser initialized. 22:25:36 INFO - Running cycle 1/1 for kraken test... 22:25:36 INFO - TEST-INFO | started process 29178 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuFl4Xq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:38 INFO - PROCESS | 29178 | 22:25:38 INFO - PROCESS | 29178 | (process:29237): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:25:48 INFO - PROCESS | 29178 | RSS: Main: 164614144 22:25:48 INFO - PROCESS | 29178 | 22:25:51 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57778/tests/kraken/driver.html?testName=audio-beat-detection) 22:25:51 INFO - PROCESS | 29178 | RSS: Main: 169222144 22:25:51 INFO - PROCESS | 29178 | 22:25:54 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57778/tests/kraken/driver.html?testName=audio-dft) 22:25:55 INFO - PROCESS | 29178 | RSS: Main: 172920832 22:25:55 INFO - PROCESS | 29178 | 22:25:58 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57778/tests/kraken/driver.html?testName=audio-fft) 22:25:58 INFO - PROCESS | 29178 | RSS: Main: 164139008 22:25:58 INFO - PROCESS | 29178 | 22:26:01 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57778/tests/kraken/driver.html?testName=audio-oscillator) 22:26:01 INFO - PROCESS | 29178 | RSS: Main: 164651008 22:26:01 INFO - PROCESS | 29178 | 22:26:03 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57778/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:26:04 INFO - PROCESS | 29178 | RSS: Main: 164962304 22:26:04 INFO - PROCESS | 29178 | 22:26:07 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57778/tests/kraken/driver.html?testName=imaging-darkroom) 22:26:08 INFO - PROCESS | 29178 | RSS: Main: 165261312 22:26:08 INFO - PROCESS | 29178 | 22:26:11 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57778/tests/kraken/driver.html?testName=imaging-desaturate) 22:26:12 INFO - PROCESS | 29178 | RSS: Main: 167444480 22:26:12 INFO - PROCESS | 29178 | 22:26:15 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57778/tests/kraken/driver.html?testName=json-parse-financial) 22:26:15 INFO - PROCESS | 29178 | RSS: Main: 167878656 22:26:15 INFO - PROCESS | 29178 | 22:26:17 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57778/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:26:17 INFO - PROCESS | 29178 | RSS: Main: 167559168 22:26:17 INFO - PROCESS | 29178 | 22:26:19 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:26:20 INFO - PROCESS | 29178 | RSS: Main: 167649280 22:26:20 INFO - PROCESS | 29178 | 22:26:22 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:26:23 INFO - PROCESS | 29178 | RSS: Main: 167796736 22:26:23 INFO - PROCESS | 29178 | 22:26:25 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:26:26 INFO - PROCESS | 29178 | RSS: Main: 168177664 22:26:26 INFO - PROCESS | 29178 | 22:26:29 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:26:29 INFO - PROCESS | 29178 | RSS: Main: 167731200 22:26:29 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | Cycle 1(1): loaded http://localhost:57778/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57778/tests/kraken/driver.html?testName=ai-astar) 22:26:31 INFO - PROCESS | 29178 | RSS: Main: 169533440 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | __start_tp_report 22:26:31 INFO - PROCESS | 29178 | _x_x_mozilla_page_load 22:26:31 INFO - PROCESS | 29178 | _x_x_mozilla_page_load_details 22:26:31 INFO - PROCESS | 29178 | |i|pagename|runs| 22:26:31 INFO - PROCESS | 29178 | |0;ai-astar;110;106;113;110;111;125;110;111;110;110 22:26:31 INFO - PROCESS | 29178 | |1;audio-beat-detection;161;127;157;172;129;152;152;137;190;136 22:26:31 INFO - PROCESS | 29178 | |2;audio-dft;163;162;162;168;161;169;175;164;166;163 22:26:31 INFO - PROCESS | 29178 | |3;audio-fft;76;77;76;149;77;77;78;73;153;76 22:26:31 INFO - PROCESS | 29178 | |4;audio-oscillator;95;94;160;95;93;98;150;92;93;152 22:26:31 INFO - PROCESS | 29178 | |5;imaging-gaussian-blur;121;117;119;117;118;117;118;117;116;117 22:26:31 INFO - PROCESS | 29178 | |6;imaging-darkroom;145;147;148;148;148;146;148;147;147;146 22:26:31 INFO - PROCESS | 29178 | |7;imaging-desaturate;96;96;96;95;95;95;96;96;95;96 22:26:31 INFO - PROCESS | 29178 | |8;json-parse-financial;56;58;56;56;57;59;57;83;56;55 22:26:31 INFO - PROCESS | 29178 | |9;json-stringify-tinderbox;60;62;60;62;61;61;61;62;61;61 22:26:31 INFO - PROCESS | 29178 | |10;stanford-crypto-aes;77;76;76;77;77;76;77;77;80;77 22:26:31 INFO - PROCESS | 29178 | |11;stanford-crypto-ccm;128;122;124;123;131;126;126;131;169;146 22:26:31 INFO - PROCESS | 29178 | |12;stanford-crypto-pbkdf2;176;171;177;173;171;168;172;168;168;176 22:26:31 INFO - PROCESS | 29178 | |13;stanford-crypto-sha256-iterative;71;69;70;75;71;74;70;67;67;69 22:26:31 INFO - PROCESS | 29178 | __end_tp_report 22:26:31 INFO - PROCESS | 29178 | __start_cc_report 22:26:31 INFO - PROCESS | 29178 | _x_x_mozilla_cycle_collect,863 22:26:31 INFO - PROCESS | 29178 | __end_cc_report 22:26:31 INFO - PROCESS | 29178 | __startTimestamp1461648391679__endTimestamp 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | ------- Summary: start ------- 22:26:31 INFO - PROCESS | 29178 | Number of tests: 14 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#0] ai-astar Cycles:10 Average:111.60 Median:110.50 stddev:5.02 (4.5%) stddev-sans-first:5.29 22:26:31 INFO - PROCESS | 29178 | Values: 110.0 106.0 113.0 110.0 111.0 125.0 110.0 111.0 110.0 110.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#1] audio-beat-detection Cycles:10 Average:151.30 Median:154.50 stddev:19.94 (12.9%) stddev-sans-first:20.84 22:26:31 INFO - PROCESS | 29178 | Values: 161.0 127.0 157.0 172.0 129.0 152.0 152.0 137.0 190.0 136.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#2] audio-dft Cycles:10 Average:165.30 Median:165.00 stddev:4.32 (2.6%) stddev-sans-first:4.50 22:26:31 INFO - PROCESS | 29178 | Values: 163.0 162.0 162.0 168.0 161.0 169.0 175.0 164.0 166.0 163.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#3] audio-fft Cycles:10 Average:91.20 Median:76.50 stddev:31.56 (41.3%) stddev-sans-first:32.99 22:26:31 INFO - PROCESS | 29178 | Values: 76.0 77.0 76.0 149.0 77.0 77.0 78.0 73.0 153.0 76.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#4] audio-oscillator Cycles:10 Average:112.20 Median:93.50 stddev:29.00 (31.0%) stddev-sans-first:30.08 22:26:31 INFO - PROCESS | 29178 | Values: 95.0 94.0 160.0 95.0 93.0 98.0 150.0 92.0 93.0 152.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:117.50 stddev:1.42 (1.2%) stddev-sans-first:0.87 22:26:31 INFO - PROCESS | 29178 | Values: 121.0 117.0 119.0 117.0 118.0 117.0 118.0 117.0 116.0 117.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.50 stddev:1.05 (0.7%) stddev-sans-first:0.83 22:26:31 INFO - PROCESS | 29178 | Values: 145.0 147.0 148.0 148.0 148.0 146.0 148.0 147.0 147.0 146.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:26:31 INFO - PROCESS | 29178 | Values: 96.0 96.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:57.00 stddev:8.41 (14.7%) stddev-sans-first:8.83 22:26:31 INFO - PROCESS | 29178 | Values: 56.0 58.0 56.0 56.0 57.0 59.0 57.0 83.0 56.0 55.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 22:26:31 INFO - PROCESS | 29178 | Values: 60.0 62.0 60.0 62.0 61.0 61.0 61.0 62.0 61.0 61.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.00 stddev:1.15 (1.5%) stddev-sans-first:1.22 22:26:31 INFO - PROCESS | 29178 | Values: 77.0 76.0 76.0 77.0 77.0 76.0 77.0 77.0 80.0 77.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:129.50 stddev:14.52 (11.2%) stddev-sans-first:15.30 22:26:31 INFO - PROCESS | 29178 | Values: 128.0 122.0 124.0 123.0 131.0 126.0 126.0 131.0 169.0 146.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:172.50 stddev:3.46 (2.0%) stddev-sans-first:3.36 22:26:31 INFO - PROCESS | 29178 | Values: 176.0 171.0 177.0 173.0 171.0 168.0 172.0 168.0 168.0 176.0 22:26:31 INFO - PROCESS | 29178 | 22:26:31 INFO - PROCESS | 29178 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.50 stddev:2.63 (3.7%) stddev-sans-first:2.77 22:26:31 INFO - PROCESS | 29178 | Values: 71.0 69.0 70.0 75.0 71.0 74.0 70.0 67.0 67.0 69.0 22:26:31 INFO - PROCESS | 29178 | -------- Summary: end -------- 22:26:31 INFO - PROCESS | 29178 | 22:26:32 INFO - TEST-INFO | 29178: exit 0 22:26:32 INFO - TEST-OK | kraken | took 61535ms 22:26:32 INFO - SUITE-END | took 1171s 22:26:32 INFO - Completed test suite (00:19:31) 22:26:32 INFO - javascript benchmark 22:26:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.2473006478444, 3173.365326934611, 3198.4402729522335, 3211.807291562508, 3217.9195520111994, 5449.4005659377435, 5450.56395488361, 5452.6455780374345, 5453.154761012044, 5492.313460817398, 5301.734913254331, 5373.382061062979, 5397.514223719864, 5399.136138217886, 5557.972210138953, 4838.419389673244, 4882.902341953159, 4931.630127740415, 4957.3059771632, 4961.677490963076, 880.7660974222608, 4791.880202994918, 4925.802967881281, 4932.53140951609, 5004.174311238641, 4878.390201224847, 4932.950670493303, 4948.430930443009, 4949.331840201581, 4973.900521989558, 4902.534259245366, 4931.087748766454, 4935.876603084916, 4962.1812400953795, 4990.201567749143, 4849.660523763338, 4881.780319885614, 4892.0705066037335, 4893.828715994923, 4912.7297998610065, 5308.191019130799, 5313.07021271276, 5359.981603495324, 5379.1662292344745, 5397.62216644835, 5642.689652069134, 5714.485696287354, 5816.82549523515, 5821.3014438267255, 5825.766969321222, 5806.535477161817, 5812.651240925558, 5834.5915785895, 5837.29952405714, 5892.469677729025, 5422.75597598109, 5427.972860135714, 5434.701091439969, 5435.320584926884, 5436.7553460094, 5834.416558344132, 5892.469677728939, 5904.9704751475965, 5974.940250597525, 6066.059760737093, 5728.427157284238, 5757.165211044375, 5761.596688231783, 5781.161731548902, 5812.651240925558, 5767.942320576741, 5809.24479817621, 5812.738426770806, 5823.417658234142, 5828.12578113278, 5627.409122042209, 5698.515626171739, 5746.224259724883, 5751.079827227707, 5775.104858746817, 5752.453516915886, 5770.711464426762, 5773.88452230953, 5780.768769249183, 5814.476697097281, 4259.339802330644, 4340.631046361032, 4384.605385515255, 4387.6270517006415, 4418.889527761831, 3842.442845787345, 3897.57126716061, 3932.0, 3951.091249012743, 3974.125692347679, 5547.889042219133, 5598.0, 5606.21512988182, 5641.717914104278, 5667.603267771299, 5681.289838770153, 5814.156947242626, 5820.505257053198, 5849.64902105875, 5874.618149820248, 5529.4470552944385, 5590.133529302964, 5592.888142237214, 5639.464250896158, 5650.039493286151, 4949.7030178189425, 4969.801207951641, 4976.104301225741, 4983.103041452501, 5039.974800125976, 5952.672603006876, 5968.910466343008, 5978.880422391528, 6014.248218972629, 6022.337542870281, 5809.709514524172, 5816.883662326645, 5822.854428639318, 5831.854203644943, 5838.503727183237, 5967.6419414835245, 6003.249593800775, 6004.099385092271, 6007.969960150346, 6016.849578760566, 5610.354809196994, 5685.374608793112, 5731.598788084794, 5736.942630573641, 5748.396418376177, 5758.395368486249, 5815.244018277766, 5854.2389489366105, 5863.589548731718, 5900.0, 5661.858453538694, 5662.801801936913, 5666.2350582670815, 5672.319321681424, 5674.517665998436, 5493.478119578634, 5654.717264136859, 5657.179708942263, 5732.71336433185, 5767.048437007847, 5601.103823388238, 5619.269494965628, 5627.915581266366, 5638.37977822431, 5651.971740141437, 5689.687067211343, 5765.336986246635, 5767.596268261182, 5767.68277744728, 5810.94189058104, 5528.308961379827, 5616.157576363578, 5694.231278777312, 5706.8573285666525, 5718.428157184248], "value": 5324.332241550221, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.8697886464979, 628.095542418917, 636.7421194416262, 656.022526435611, 676.5061505101273, 38073.42889856651, 40118.19763604726, 40145.79927100364, 40197.19605607886, 40311.0, 40530.0, 40674.389884151686, 41048.794756026255, 41118.794406027926, 41375.58624413755, 8322.916770832306, 8656.956715216447, 8666.30669546438, 8682.26200772932, 8687.95656021719, 8484.830303393928, 8760.561971901412, 8839.204471597555, 8844.027157012724, 8854.07032261613, 8817.823643527126, 8932.464052156858, 8935.821283574325, 8950.686725964593, 8963.686270980517, 8817.294616430701, 8903.510306933149, 8918.687845925426, 8932.151445612657, 8936.32977526688, 8786.297096232285, 8802.559872006437, 8866.246369058637, 8934.240589549896, 8936.285097192209, 28093.438131237464, 40944.181116377506, 41153.58846411527, 41169.794151029055, 41460.1707965842, 3415.79505229687, 3438.8796392126405, 3511.0, 3582.8925132245813, 3591.2099338145567, 28294.717052829415, 28656.426871462452, 28820.567691484644, 28963.13110606664, 29094.27264318409, 3959.7426167299323, 4032.858849940238, 4119.464469618931, 4180.874573762761, 4353.085851971058, 272556.0, 276101.6194919033, 276166.0, 277666.0, 279097.0, 56609.71695141498, 57117.0, 57243.71378143083, 57376.0, 57517.712411438515, 55006.44993550093, 56404.71797641068, 56526.43473565294, 56757.7162114195, 59572.702136489905, 16013.919930400274, 16136.273867675984, 16144.112073835817, 16162.191890405433, 16230.269637866088, 12643.683907902192, 12757.298348590917, 12762.489500419879, 12782.68043298925, 12804.61586152417, 19411.417657470298, 19738.901305493382, 19760.60478790445, 19844.206231750857, 19920.50198745043, 7123.501354905107, 7208.96395518019, 7240.298529845482, 7342.559446433231, 7404.148522919825, 1053.7154968158554, 1066.4347895615335, 1088.9564417423214, 1105.8285965675334, 1111.349860331699, 30942.590226764667, 31350.686493135232, 31537.52693709596, 31606.367872642877, 31707.36585268282, 1125.983110253347, 1136.8294755786699, 1140.0537553830302, 1173.6185739634652, 1193.5404869125275, 16506.75239871403, 16706.331746730237, 16861.325546978227, 16929.746053809202, 17247.81788216319, 545666.0, 548999.0, 550853.0, 550941.0, 557854.0, 57441.13838292429, 57924.710376447845, 57978.13032804511, 58120.0, 58207.41792582105, 1337.284552764286, 1350.0549615269351, 1365.938532766028, 1370.280602683599, 1379.1518216296904, 30554.0, 31096.378072438878, 31297.687023129478, 31674.0, 32203.838980804947, 43308.78345608252, 47307.763461182476, 49035.774105647644, 49120.2631960528, 49283.75358123187, 359032.0, 362238.0, 363583.0, 365347.0, 367295.0, 39923.40114898337, 39968.80015599904, 40103.59896400999, 40259.79870100631, 40371.79814100911, 44671.10657786891, 44957.0, 45069.54930450654, 45518.317225240986, 45821.77089114533, 39601.52674806631, 39811.80094099627, 40315.79842100888, 40327.193456130124, 41165.17669646531, 38828.22343553056, 39971.40042899301, 40596.594034060465, 40729.38905916473, 41137.0, 41051.0, 41374.37938430866, 41993.58006419897, 42178.36732448954, 42475.787621061696, 34042.31915361728, 36292.81853590715, 36447.45328820123, 36571.26857462889, 36692.63307366892, 28271.2932176689, 28690.0, 28849.278768030967, 28953.276168095967, 28993.0], "value": 19788.04835736932, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.64312724475366, 382.5103867050176, 383.38466760848837, 392.84678975199677, 394.9229900169468, 15092.773608395883, 16830.915845420757, 17313.307467701292, 19758.506037349056, 20191.798082019177, 17698.82301176991, 18069.4579162625, 18417.539561510934, 18548.90725546374, 18574.81425185748, 5544.528715059215, 5664.575156863231, 5679.4036626154275, 5706.800261990831, 5709.371969083398, 5028.647994640377, 5269.6311258211945, 5292.126799078147, 5303.681779093247, 5353.678779273237, 5254.343207099113, 5254.658447200939, 5256.0, 5339.6262261641705, 6170.382961703838, 6154.784582539634, 6169.043798211284, 6182.258129024523, 6186.505079593621, 6206.472449841792, 5237.685738855643, 5340.465953404629, 5357.946420535784, 5358.383785864637, 5535.335759708861, 21700.34898953034, 22232.0, 22415.21546745856, 22516.662250066263, 22603.287605487447, 2191.1345018717516, 2303.343547089088, 2386.9880650596638, 2496.450780828215, 2515.748425157488, 9781.46201958892, 10163.796724065478, 14362.928185359006, 14443.711125777425, 14541.927290363587, 1938.0987840654022, 2017.1427143463968, 2031.6343058249508, 2035.8982050897544, 2047.8668886522328, 82343.5882820588, 86478.0, 87517.12482875153, 88189.0, 88505.55747221287, 10482.37105773656, 10507.684769456928, 10724.14206863449, 10772.515236814363, 10982.395968221665, 15079.849201507845, 15262.16058116792, 15338.846611533741, 15349.923250383677, 15350.692986140215, 7824.139344672081, 8197.590120493951, 8248.340132789483, 8368.0, 8369.581520924052, 7922.722704705423, 7926.365890728728, 8010.118886922434, 8043.436959412902, 8140.470869393471, 4069.613386728315, 4571.519990401027, 4576.633869290449, 4597.103564804898, 4680.812767489262, 1826.678837910786, 1911.388355726182, 1912.1490936533387, 1912.3880358285494, 1931.9613607727767, 890.973270801877, 988.9159421449258, 1020.4285600063988, 1045.440689231273, 1068.3483075324045, 18189.818101819077, 18585.81414185868, 18849.528761781065, 18956.715649265272, 19356.32252871143, 923.9076092390842, 951.2437612098398, 962.764122789921, 973.7419583810296, 979.407458487619, 10350.741231469123, 10710.410927398912, 10744.301620394806, 10764.0, 10815.134789217002, 511148.0, 514849.0, 516507.0, 520294.0, 529385.0, 92358.53820730853, 92386.0, 94661.0, 95072.0, 96824.51587742016, 992.8212921674024, 1007.8941711120227, 1018.872640919885, 1028.5885645741614, 1034.0538407357335, 16481.17594120313, 17051.48845534636, 17110.486685399457, 17250.568735781704, 17407.82592174062, 38939.0, 39860.80069599633, 40234.79882600568, 40597.79701101594, 41558.0, 56132.43867561272, 56410.43589564052, 56550.71724641351, 56866.715666421405, 57125.42874571201, 8046.798830029296, 8382.455140415854, 8399.95800020996, 8524.872126917977, 8616.310695144248, 9313.161815436642, 11589.478473468545, 12767.361631918553, 12872.292023938773, 13066.150700204456, 11453.713657158305, 11733.530658773894, 11971.940140299592, 12134.635960921187, 12193.207441516273, 11504.0, 11573.710657233636, 11662.241954272946, 11666.0, 12111.878881211074, 12069.0, 12111.878881211074, 12191.573294934635, 12529.561465349034, 12832.55086071983, 8536.829263414822, 8576.742697719079, 8676.305895528216, 8692.130786921258, 8861.468311901306, 10806.891931080589, 11157.776844463227, 11527.711807204887, 11572.24780389272, 11601.767964640829], "value": 9179.886465379495, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.5219877440443, 1470.1620076556362, 1579.2261791722053, 1723.6035711786296, 1761.2690733345655, 7586.013818203626, 8055.476394034384, 8126.390520710948, 8446.15538446155, 8483.533405662694, 8056.798580035487, 8088.91911080889, 8104.756857294291, 8143.0, 8201.179882011797, 2244.0911430870524, 2271.318604418676, 2280.7149106361703, 2290.1297506947335, 2290.2442194075916, 2244.674522194281, 2261.502469456725, 2311.953760924789, 2312.0520586559514, 2316.247219653611, 2240.215924426446, 2270.761570035147, 2287.874166920819, 2346.5189636124555, 2347.3427440316736, 2241.316398498457, 2256.729192496911, 2264.467850055236, 2264.558411109834, 2265.139247086105, 2240.8095311898346, 2251.9324420267417, 2265.5468906218657, 2291.51878105598, 2323.7560056194025, 8258.42191046627, 8320.958395208047, 8322.375821813328, 8381.916180838236, 8383.16168383163, 4964.826231081931, 5158.2520534522655, 5187.403448603422, 5213.218017297436, 5228.398734145584, 3057.9235519111976, 3214.758893083028, 3356.848941797625, 3377.5778027746533, 3383.0527452313145, 1056.6988408303673, 1068.2628985999636, 1092.3500517192258, 1108.927919685226, 1109.6837401340579, 11525.884741152566, 11553.826692599618, 11564.999999999916, 11589.072874170046, 11592.710182245428, 4478.283474644107, 4482.394876691605, 4647.047355292157, 4653.139169253699, 4699.765011749399, 9811.460369679593, 9814.852777208205, 9815.558299876522, 9822.361546499455, 9864.654737084167, 5293.57651387888, 5298.788048478018, 5308.575313974873, 5312.601554883322, 5312.76092575835, 4318.093200427946, 4478.910421791546, 4694.6713730039255, 4760.285957106462, 4891.119598472238, 3791.109089363997, 3820.4842346283453, 3923.725339226227, 3929.8035098244973, 3984.143409167043, 1256.0830593666674, 1261.873812618749, 1268.4355461819403, 1275.6300672804969, 1306.5884246462333, 903.074348792493, 929.1034152043311, 960.3325688646323, 962.4562122400821, 968.651285537206, 5340.225667278223, 5445.50990410865, 5498.45015498455, 5731.684757338387, 5769.9711501442225, 418.44764910318696, 423.2825361013053, 435.46437881406047, 435.71460693245973, 449.3327408797932, 3966.5636779954184, 3996.660283875845, 4001.519817621904, 4016.5180178378782, 4086.7752273625238, 10113.0, 10331.380117192992, 10415.906329835258, 10558.894411055944, 10568.260221784401, 10262.743431414274, 10303.021212984755, 10354.378737275636, 10436.060754531976, 10467.110295624958, 7519.436042296849, 7882.448228624056, 7904.604769761489, 7905.0, 8053.033635963722, 5786.132080187837, 5806.129080637938, 5905.4685078343155, 5936.990711579128, 5954.851128721643, 9024.36829421934, 9530.142287194185, 9576.425414475154, 9640.90359096428, 9715.70852874415, 9349.813003739751, 9356.625734970525, 9373.484458354857, 9391.436513809193, 9466.148046675833, 6928.272531384132, 7182.640867956686, 7439.921211424421, 7445.218252083453, 7622.0, 7939.603019848869, 7970.043594768665, 7982.08206056311, 8013.6794528218215, 8017.559034253172, 7113.430925526049, 7288.672009759467, 7392.630368481447, 7400.074990626172, 7434.1450733166375, 7191.532550384209, 7239.529430586995, 7246.4202863771825, 7259.963700181465, 7304.160021597584, 6604.174478190226, 6786.69459874297, 6806.489513286424, 6832.111825462658, 6835.316468353125, 4825.5174482551465, 4865.683730557503, 4889.584385327287, 5190.091733946469, 5267.499587539178, 5158.509941555546, 5210.53105220532, 5220.504052115043, 5239.083160446983, 5289.418164002033], "value": 4395.45522849132, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.43851943540494, 322.5532637297343, 323.8882585507999, 328.89146581628063, 329.0326440265619, 5390.51485366317, 5431.592630552705, 5474.507294343508, 5539.639923404976, 5596.972015139923, 5481.5340696040885, 5498.395176530588, 5502.147167189092, 5515.0, 5517.84803926469, 4281.5290318065145, 4283.935740963888, 4309.224339618867, 4324.243257429937, 4327.783610819462, 4214.641755450775, 4257.020485474254, 4265.914681706379, 4268.167707297078, 4291.8497852575165, 4193.643540299078, 4210.97894510527, 4225.91548169038, 4243.072280126718, 4251.638610718092, 4219.135077309175, 4258.808353624064, 4262.147570485899, 4283.657307415399, 4292.356146578023, 3702.7037836972977, 3761.191343861083, 3775.1883345080664, 3787.791671458069, 3821.0638393593467, 5275.28783614211, 5299.4700529946695, 5321.6540924839765, 5340.62615616907, 5343.599230057723, 4851.830185943475, 4899.216125419915, 4908.680935739166, 4934.481879402683, 4959.70241785494, 5607.943920560824, 5739.282589676291, 5743.971280143573, 5773.278340207474, 5778.0, 4115.34154535276, 4136.958630413687, 4162.354835000579, 4175.394567787654, 4179.247735407625, 5987.970060149715, 6016.909746353808, 6022.175918291872, 6045.697715114226, 6066.787662431793, 4049.8785036448953, 4136.21411931738, 4151.958480415157, 4166.354215096665, 4182.707210495297, 4256.893577660521, 4274.883932453456, 4275.178382267688, 4300.268954277781, 4308.18144552534, 4885.560299572984, 4887.608991280689, 4899.412070551485, 4927.063857866994, 4943.0, 5755.913661295084, 5828.854278643068, 5872.0, 5922.3485416604135, 5930.34766175729, 1552.0842702805398, 1561.3130222701975, 1564.4915402494235, 1567.902468272214, 1571.4107209796327, 1032.736652153696, 1043.5773511727762, 1044.6865940217904, 1045.968620941373, 1054.8154073037342, 5957.136215248851, 5968.8806223876145, 5974.163617093611, 5986.2517185351835, 5987.43119403656, 6366.840828979312, 6371.203599550056, 6375.489960803125, 6418.390252925965, 6420.7431702731365, 5814.738336774855, 5965.254343207099, 5999.250093738283, 5999.730012149464, 6014.729337179838, 3394.405978953673, 3396.337714145767, 3400.6599340065795, 3410.914727131792, 3428.211511352403, 6490.870182596416, 6544.476441884638, 6594.274629790719, 6654.234763002317, 6659.367360100783, 6482.67586620667, 6585.440237579764, 6586.571872828251, 6605.5376123670885, 6614.7023383947835, 5849.64902105875, 6216.533759968021, 6592.604443733391, 6600.273969863311, 6646.767363142267, 5985.431384018511, 5995.770866972348, 6006.87986240264, 6013.458788709037, 6046.486048685911, 5976.820695379146, 5980.0, 5989.490893274121, 5993.760249589967, 5998.760049598141, 5930.614510057005, 6233.937660623335, 6237.625742455467, 6253.843653908689, 6259.593126446766, 3937.8621748238675, 4049.9392509112977, 4182.351735481006, 4188.308929026676, 4195.964956995916, 5881.470667639762, 5938.524918006463, 5960.688648497324, 5964.850878728066, 5975.16347711312, 5728.312602487562, 5784.21913041734, 5829.8251052468495, 5847.502962248256, 5870.706464676835, 5794.507466865363, 5815.302163740378, 5825.2135961644635, 5845.3570107289015, 5858.619189752652, 5763.279590051244, 5764.204360885051, 5767.048437007847, 5771.691323470645, 5771.903338365697, 5583.860403489945, 5870.412958704095, 5876.559258055749, 5881.088431293157, 5905.261842269716, 5628.60599758013, 5702.486776190163, 5711.400302968294, 5732.226149469935, 5758.740856661377], "value": 4483.830957774943, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.8424315136972, 811.1280373598381, 837.7989282572184, 840.1640367834007, 852.3011130872679, 13682.657933551665, 14096.0, 14141.71716565668, 14347.21090340031, 14353.856461435369, 12321.876781232186, 12341.568045118423, 12446.813297800518, 12798.552050678229, 12884.871151288484, 674.9898751518731, 784.7253461288532, 785.1363500149847, 792.7502836606451, 795.7413840501831, 796.8326651403212, 800.1918062756629, 802.8033131882697, 805.375833728859, 806.1535387842771, 841.9915800842005, 845.2899564365931, 845.4631309118723, 845.7335939179139, 850.3622283287534, 843.8312337532487, 843.9956451822312, 845.488479469918, 847.9533625650648, 851.4423052900329, 617.0158597037718, 619.5198720991223, 620.8447888027993, 620.9254889413254, 621.8103478439074, 14284.500042498463, 14326.140431574035, 14381.640458988504, 14445.566633001026, 14448.494302699355, 14288.285585720776, 14409.639759006004, 14467.602382243662, 14535.200563968978, 14565.708685826225, 10926.672199834018, 10982.286151400212, 11087.944560277228, 11096.0, 11275.267107637977, 3867.16855875988, 3874.8062596870327, 3881.4954055972826, 4039.1921615676983, 4063.106116654361, 55819.44180558224, 56280.0, 56408.0, 56487.435125648626, 56640.43359566434, 11263.605773797877, 11289.379084150287, 11354.091672666167, 11361.14791390649, 11690.240134391237, 20336.69494957577, 20445.079971401323, 20451.693224601644, 20454.795452045586, 20470.488237793877, 8067.515949043076, 8168.550729709923, 8224.300934420522, 8258.42191046633, 8503.362247831437, 4621.95378046222, 4624.491305956342, 4681.976590117096, 4771.069641419959, 4794.448638406628, 3290.0952238134228, 3364.444866597033, 3365.7139143172617, 3374.8481318340732, 3384.1370450535446, 548.1667864845413, 548.1667864845413, 551.9061759500895, 556.8802707417925, 577.1458241802072, 628.5348841856993, 725.1624373848214, 725.2928394815013, 756.9432292578078, 765.6745882999703, 8511.106333834858, 8710.085441028728, 8814.427062240933, 8933.106689331145, 8941.910580894239, 639.5139693832627, 680.7719413996367, 723.3200791256307, 731.6670914733784, 738.1732459645232, 4339.088791353788, 4373.518912919576, 4387.122575484916, 4387.890302742393, 4404.867853964386, 64502.26187798393, 65134.0, 66215.33784662189, 66655.66672166609, 66686.33313666898, 58504.41495585075, 58522.4147758517, 59721.70139149277, 60637.0, 61979.070313945325, 54386.728066359414, 55327.72336138374, 55909.16136258041, 55964.0, 56064.71967640136, 8471.745847624581, 8596.828063438572, 8714.259287960595, 8736.958097148017, 8779.780505487159, 36851.447228292134, 37202.25595488129, 37346.626533734314, 37547.62452375441, 38454.2309153821, 47731.52268477271, 48419.0, 48489.0, 49971.50028499814, 50812.49187508078, 11187.32876027441, 11295.604653837076, 11728.41357932117, 12189.573364932185, 12633.557825476064, 11972.401379930794, 12003.699907502032, 12391.132620716464, 12517.687057823627, 12974.545890893773, 11987.34069626179, 12047.216930899463, 12073.094517911368, 12082.758344832879, 12322.630321090382, 11181.776364472827, 11266.718332041764, 11880.643580692593, 11897.821532676844, 11984.700382490508, 10523.526441310312, 10919.962603552649, 11649.242799218024, 11798.823017654913, 11836.349000804694, 8951.149640784115, 9486.905130948879, 9661.613535458784, 9675.516224188903, 9973.800523989334, 9314.580843862179, 10059.49702514886, 10352.17182625373, 10397.688069357931, 10709.410982396043], "value": 6695.590276926373, "unit": "score"}], "name": "dromaeo_css", "value": 7095.7545130347, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 113.0, 110.0, 111.0, 125.0, 110.0, 111.0, 110.0, 110.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 127.0, 157.0, 172.0, 129.0, 152.0, 152.0, 137.0, 190.0, 136.0], "value": 151.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 168.0, 161.0, 169.0, 175.0, 164.0, 166.0, 163.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 149.0, 77.0, 77.0, 78.0, 73.0, 153.0, 76.0], "value": 91.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 160.0, 95.0, 93.0, 98.0, 150.0, 92.0, 93.0, 152.0], "value": 112.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 117.0, 119.0, 117.0, 118.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 148.0, 148.0, 148.0, 146.0, 148.0, 147.0, 147.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 56.0, 57.0, 59.0, 57.0, 83.0, 56.0, 55.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 62.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 77.0, 77.0, 76.0, 77.0, 77.0, 80.0, 77.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 122.0, 124.0, 123.0, 131.0, 126.0, 126.0, 131.0, 169.0, 146.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 171.0, 177.0, 173.0, 171.0, 168.0, 172.0, 168.0, 168.0, 176.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 75.0, 71.0, 74.0, 70.0, 67.0, 67.0, 69.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1564.1999999999998, "alertThreshold": 2.0}]} 22:26:32 INFO - Return code: 0 22:26:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:26:32 ERROR - # TBPL SUCCESS # 22:26:32 INFO - Running post-action listener: _resource_record_post_action 22:26:32 INFO - Running post-run listener: _resource_record_post_run 22:26:34 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 304660480; Read time: 16; Write time: 217192 22:26:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13905920; Read time: 0; Write time: 4448 22:26:34 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287272960; Read time: 16; Write time: 202640 22:26:34 INFO - Running post-run listener: _upload_blobber_files 22:26:34 INFO - Blob upload gear active. 22:26:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:26:34 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 22:26:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:26:34 INFO - (blobuploader) - INFO - Open directory for files ... 22:26:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:26:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:26:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:38 INFO - (blobuploader) - INFO - Done attempting. 22:26:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:26:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:26:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:39 INFO - (blobuploader) - INFO - Done attempting. 22:26:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:26:39 INFO - Return code: 0 22:26:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:26:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:26:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34c5c6256f8e3529e5d79033094b3d44d91999a6a52a95b3b87268cdb81446cedf9ef5c9e73deadbe2d1e83aaeec4d3d32969c1e8ec68b9ba1951c98cecf3f9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac112ad56e318682fc3dc21e7ed4ba33f102ec4c3f2663c9dcd3de652ecb4c9a97daec84db496f31fda0b1bf628b6f11d86f0ed531a74ee9853d44b3a82c2ddd"} 22:26:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:26:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:26:39 INFO - Contents: 22:26:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34c5c6256f8e3529e5d79033094b3d44d91999a6a52a95b3b87268cdb81446cedf9ef5c9e73deadbe2d1e83aaeec4d3d32969c1e8ec68b9ba1951c98cecf3f9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac112ad56e318682fc3dc21e7ed4ba33f102ec4c3f2663c9dcd3de652ecb4c9a97daec84db496f31fda0b1bf628b6f11d86f0ed531a74ee9853d44b3a82c2ddd"} 22:26:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:26:39 INFO - Copying logs to upload dir... 22:26:39 INFO - mkdir: /builds/slave/test/build/upload/logs 22:26:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.305030 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-04-25 22:26:39.310984) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 22:26:39.314137) ========= 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=20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34c5c6256f8e3529e5d79033094b3d44d91999a6a52a95b3b87268cdb81446cedf9ef5c9e73deadbe2d1e83aaeec4d3d32969c1e8ec68b9ba1951c98cecf3f9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac112ad56e318682fc3dc21e7ed4ba33f102ec4c3f2663c9dcd3de652ecb4c9a97daec84db496f31fda0b1bf628b6f11d86f0ed531a74ee9853d44b3a82c2ddd"} build_url:https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/BgRLa5UbS4Wtn6n6d33d4g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34c5c6256f8e3529e5d79033094b3d44d91999a6a52a95b3b87268cdb81446cedf9ef5c9e73deadbe2d1e83aaeec4d3d32969c1e8ec68b9ba1951c98cecf3f9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac112ad56e318682fc3dc21e7ed4ba33f102ec4c3f2663c9dcd3de652ecb4c9a97daec84db496f31fda0b1bf628b6f11d86f0ed531a74ee9853d44b3a82c2ddd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 22:26:39.338822) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:26:39.339160) ========= 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=20e666a3d4cfbb71505a330e000001b4-1461643346.719619-1898577270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:26:39.422491) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 22:26:39.422815) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 22:26:39.423113) ========= ========= Total master_lag: 0.18 =========