builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-023 starttime: 1469447219.49 results: success (0) buildid: 20160725015529 builduid: 7e02ace4dd9847cab657ff55c8b108f3 revision: 59caf5ec41614bf5de43d45f41a1decee29a869d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.489133) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.489589) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.489869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.505085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.505442) ========= 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=6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-25 04:47:00-- 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% 70.8M=0s 2016-07-25 04:47:00 (70.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117361 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.637145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.637438) ========= 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=6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016265 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:46:59.687224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 04:46:59.687619) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 59caf5ec41614bf5de43d45f41a1decee29a869d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 59caf5ec41614bf5de43d45f41a1decee29a869d --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=6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-25 04:47:00,344 truncating revision to first 12 chars 2016-07-25 04:47:00,344 Setting DEBUG logging. 2016-07-25 04:47:00,344 attempt 1/10 2016-07-25 04:47:00,344 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/59caf5ec4161?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 04:47:01,745 unpacking tar archive at: mozilla-beta-59caf5ec4161/testing/mozharness/ program finished with exit code 0 elapsedTime=1.744162 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 04:47:01.447703) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 04:47:01.448025) ========= script_repo_revision: 59caf5ec41614bf5de43d45f41a1decee29a869d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 04:47:01.448416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 04:47:01.448725) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 04:47:01.459777) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-07-25 04:47:01.460044) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:47:02 INFO - MultiFileLogger online at 20160725 04:47:02 in /builds/slave/test 04:47:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:47:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:02 INFO - {'append_to_log': False, 04:47:02 INFO - 'base_work_dir': '/builds/slave/test', 04:47:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:47:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:02 INFO - 'branch': 'Mozilla-Beta', 04:47:02 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:02 INFO - 'config_files': ('talos/linux_config.py',), 04:47:02 INFO - 'default_actions': ('clobber', 04:47:02 INFO - 'read-buildbot-config', 04:47:02 INFO - 'download-and-extract', 04:47:02 INFO - 'populate-webroot', 04:47:02 INFO - 'create-virtualenv', 04:47:02 INFO - 'install', 04:47:02 INFO - 'run-tests'), 04:47:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:02 INFO - 'download_minidump_stackwalk': True, 04:47:02 INFO - 'download_symbols': 'ondemand', 04:47:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:02 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:02 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:02 INFO - 'installer_path': 'installer.exe', 04:47:02 INFO - 'log_level': 'info', 04:47:02 INFO - 'log_name': 'talos', 04:47:02 INFO - 'log_to_console': True, 04:47:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:47:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:47:02 INFO - 'opt_config_files': (), 04:47:02 INFO - 'pip_index': False, 04:47:02 INFO - 'sps_profile': False, 04:47:02 INFO - 'sps_profile_interval': 0, 04:47:02 INFO - 'suite': 'dromaeojs-e10s', 04:47:02 INFO - 'system_bits': '32', 04:47:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:02 INFO - 'title': 'talos-linux64-ix-023', 04:47:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:02 INFO - 'use_talos_json': True, 04:47:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:02 INFO - 'work_dir': 'build'} 04:47:02 INFO - ##### 04:47:02 INFO - ##### Running clobber step. 04:47:02 INFO - ##### 04:47:02 INFO - Running pre-action listener: _resource_record_pre_action 04:47:02 INFO - Running main action method: clobber 04:47:02 INFO - rmtree: /builds/slave/test/build 04:47:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:47:02 INFO - Running post-action listener: _resource_record_post_action 04:47:02 INFO - ##### 04:47:02 INFO - ##### Running read-buildbot-config step. 04:47:02 INFO - ##### 04:47:02 INFO - Running pre-action listener: _resource_record_pre_action 04:47:02 INFO - Running main action method: read_buildbot_config 04:47:02 INFO - Using buildbot properties: 04:47:02 INFO - { 04:47:02 INFO - "project": "", 04:47:02 INFO - "product": "firefox", 04:47:02 INFO - "script_repo_revision": "production", 04:47:02 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 04:47:02 INFO - "repository": "", 04:47:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 04:47:02 INFO - "buildid": "20160725015529", 04:47:02 INFO - "pgo_build": "True", 04:47:02 INFO - "basedir": "/builds/slave/test", 04:47:02 INFO - "buildnumber": 185, 04:47:02 INFO - "slavename": "talos-linux64-ix-023", 04:47:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:47:02 INFO - "platform": "ubuntu64_hw", 04:47:02 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 04:47:02 INFO - "branch": "mozilla-beta", 04:47:02 INFO - "repo_path": "releases/mozilla-beta", 04:47:02 INFO - "revision": "59caf5ec41614bf5de43d45f41a1decee29a869d", 04:47:02 INFO - "stage_platform": "linux64-pgo", 04:47:02 INFO - "builduid": "7e02ace4dd9847cab657ff55c8b108f3", 04:47:02 INFO - "slavebuilddir": "test" 04:47:02 INFO - } 04:47:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 04:47:02 INFO - Running post-action listener: _resource_record_post_action 04:47:02 INFO - ##### 04:47:02 INFO - ##### Running download-and-extract step. 04:47:02 INFO - ##### 04:47:02 INFO - Running pre-action listener: _resource_record_pre_action 04:47:02 INFO - Running main action method: download_and_extract 04:47:02 INFO - mkdir: /builds/slave/test/build/tests 04:47:02 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')]} 04:47:02 INFO - https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:47:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 04:47:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 04:47:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 04:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:47:04 INFO - Downloaded 1327 bytes. 04:47:04 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 04:47:04 INFO - Using the following test package requirements: 04:47:04 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 04:47:04 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 04:47:04 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 04:47:04 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'jsshell-linux-x86_64.zip'], 04:47:04 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 04:47:04 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 04:47:04 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 04:47:04 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 04:47:04 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 04:47:04 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:47:04 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 04:47:04 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:47:04 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')]} 04:47:04 INFO - https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:47:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:47:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:47:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:47:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:47:06 INFO - Downloaded 24200543 bytes. 04:47:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:47:08 INFO - Return code: 0 04:47:08 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:08 INFO - https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:47:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:47:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:47:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:47:12 INFO - Downloaded 24200543 bytes. 04:47:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:47:13 INFO - Return code: 0 04:47: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')]} 04:47:13 INFO - https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:47:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 04:47:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 04:47:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 04:47:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:47:15 INFO - Downloaded 10840145 bytes. 04:47:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:47:15 INFO - Return code: 0 04:47: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')]} 04:47:15 INFO - https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:47:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:47:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:47:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:47:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:47:17 INFO - Downloaded 56025200 bytes. 04:47:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:47:17 INFO - mkdir: /builds/slave/test/properties 04:47:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:17 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:17 INFO - Contents: 04:47:17 INFO - build_url:https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:47:17 INFO - Running post-action listener: _resource_record_post_action 04:47:17 INFO - Running post-action listener: set_extra_try_arguments 04:47:17 INFO - ##### 04:47:17 INFO - ##### Running populate-webroot step. 04:47:17 INFO - ##### 04:47:17 INFO - Running pre-action listener: _resource_record_pre_action 04:47:17 INFO - Running main action method: populate_webroot 04:47:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:17 INFO - '--tppagecycles', 04:47:17 INFO - '7'], 04:47:17 INFO - 'tests': ['tsvgm']}}, 04:47:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['damp', 'tps']}, 04:47:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'tests': ['damp', 'tps']}, 04:47:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['dromaeo_dom']}, 04:47:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['a11yr', 04:47:17 INFO - 'ts_paint', 04:47:17 INFO - 'tpaint', 04:47:17 INFO - 'sessionrestore', 04:47:17 INFO - 'sessionrestore_no_auto_restore', 04:47:17 INFO - 'tabpaint']}, 04:47:17 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:17 INFO - 'ts_paint', 04:47:17 INFO - 'tpaint', 04:47:17 INFO - 'sessionrestore', 04:47:17 INFO - 'sessionrestore_no_auto_restore', 04:47:17 INFO - 'tabpaint']}, 04:47:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:17 INFO - 'ts_paint', 04:47:17 INFO - 'tpaint', 04:47:17 INFO - 'sessionrestore', 04:47:17 INFO - 'sessionrestore_no_auto_restore', 04:47:17 INFO - 'tabpaint']}, 04:47:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:17 INFO - 'ts_paint', 04:47:17 INFO - 'tpaint', 04:47:17 INFO - 'sessionrestore', 04:47:17 INFO - 'sessionrestore_no_auto_restore', 04:47:17 INFO - 'tabpaint']}, 04:47:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['a11yr', 04:47:17 INFO - 'ts_paint', 04:47:17 INFO - 'tpaint', 04:47:17 INFO - 'sessionrestore', 04:47:17 INFO - 'sessionrestore_no_auto_restore', 04:47:17 INFO - 'tabpaint']}, 04:47:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['a11yr', 04:47:17 INFO - 'ts_paint', 04:47:17 INFO - 'tpaint', 04:47:17 INFO - 'sessionrestore', 04:47:17 INFO - 'sessionrestore_no_auto_restore', 04:47:17 INFO - 'tabpaint']}, 04:47:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['tsvgx', 04:47:17 INFO - 'tsvgr_opacity', 04:47:17 INFO - 'tart', 04:47:17 INFO - 'tscrollx', 04:47:17 INFO - 'cart']}, 04:47:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:17 INFO - 'tsvgr_opacity', 04:47:17 INFO - 'tart', 04:47:17 INFO - 'tscrollx', 04:47:17 INFO - 'cart']}, 04:47:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'talos_options': ['--disable-e10s'], 04:47:17 INFO - 'tests': ['tp5o']}, 04:47:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'tests': ['tp5o']}, 04:47:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'talos_options': ['--disable-e10s', 04:47:17 INFO - '--xperf_path', 04:47:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:17 INFO - 'tests': ['tp5n']}, 04:47:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:17 INFO - 'talos_options': ['--xperf_path', 04:47:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:17 INFO - 'tests': ['tp5n']}}, 04:47:17 INFO - 'talos.zip': {'path': '', 04:47:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:17 INFO - Running post-action listener: _resource_record_post_action 04:47:17 INFO - ##### 04:47:17 INFO - ##### Running create-virtualenv step. 04:47:17 INFO - ##### 04:47:17 INFO - Running pre-action listener: _resource_record_pre_action 04:47:17 INFO - Running main action method: create_virtualenv 04:47:17 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:17 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 04:47:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:17 INFO - Using real prefix '/usr' 04:47:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:18 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:20 INFO - Installing pip.................done. 04:47:20 INFO - Return code: 0 04:47:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47: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 04:47: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 04:47:20 INFO - Using env: {'DISPLAY': ':0', 04:47:20 INFO - 'HOME': '/home/cltbld', 04:47:20 INFO - 'LANG': 'en_US.UTF-8', 04:47:20 INFO - 'LANGUAGE': 'en_US:en', 04:47:20 INFO - 'LOGNAME': 'cltbld', 04:47:20 INFO - 'MAIL': '/var/mail/cltbld', 04:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:20 INFO - 'MOZ_NO_REMOTE': '1', 04:47:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:20 INFO - 'NO_EM_RESTART': '1', 04:47:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:20 INFO - 'PWD': '/builds/slave/test', 04:47:20 INFO - 'SHELL': '/bin/bash', 04:47:20 INFO - 'SHLVL': '1', 04:47:20 INFO - 'TERM': 'linux', 04:47:20 INFO - 'TMOUT': '86400', 04:47:20 INFO - 'USER': 'cltbld', 04:47:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:20 INFO - '_': '/tools/buildbot/bin/python'} 04:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47: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 04:47:20 INFO - Cleaning up... 04:47:20 INFO - Return code: 0 04:47:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47: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 04:47: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 04:47:20 INFO - Using env: {'DISPLAY': ':0', 04:47:20 INFO - 'HOME': '/home/cltbld', 04:47:20 INFO - 'LANG': 'en_US.UTF-8', 04:47:20 INFO - 'LANGUAGE': 'en_US:en', 04:47:20 INFO - 'LOGNAME': 'cltbld', 04:47:20 INFO - 'MAIL': '/var/mail/cltbld', 04:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:20 INFO - 'MOZ_NO_REMOTE': '1', 04:47:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:20 INFO - 'NO_EM_RESTART': '1', 04:47:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:20 INFO - 'PWD': '/builds/slave/test', 04:47:20 INFO - 'SHELL': '/bin/bash', 04:47:20 INFO - 'SHLVL': '1', 04:47:20 INFO - 'TERM': 'linux', 04:47:20 INFO - 'TMOUT': '86400', 04:47:20 INFO - 'USER': 'cltbld', 04:47:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:20 INFO - '_': '/tools/buildbot/bin/python'} 04:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:20 INFO - Downloading/unpacking psutil>=0.7.1 04:47:20 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 04:47:20 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 04:47:20 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 04:47:20 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 04:47:20 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 04:47:20 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 04:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:22 INFO - Installing collected packages: psutil 04:47:22 INFO - Running setup.py install for psutil 04:47:22 INFO - building 'psutil._psutil_linux' extension 04:47:22 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 04:47: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 04:47:23 INFO - building 'psutil._psutil_posix' extension 04:47: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 04:47: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 04:47:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:23 INFO - Successfully installed psutil 04:47:23 INFO - Cleaning up... 04:47:23 INFO - Return code: 0 04:47:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:47:23 INFO - Using env: {'DISPLAY': ':0', 04:47:23 INFO - 'HOME': '/home/cltbld', 04:47:23 INFO - 'LANG': 'en_US.UTF-8', 04:47:23 INFO - 'LANGUAGE': 'en_US:en', 04:47:23 INFO - 'LOGNAME': 'cltbld', 04:47:23 INFO - 'MAIL': '/var/mail/cltbld', 04:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:23 INFO - 'MOZ_NO_REMOTE': '1', 04:47:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:23 INFO - 'NO_EM_RESTART': '1', 04:47:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:23 INFO - 'PWD': '/builds/slave/test', 04:47:23 INFO - 'SHELL': '/bin/bash', 04:47:23 INFO - 'SHLVL': '1', 04:47:23 INFO - 'TERM': 'linux', 04:47:23 INFO - 'TMOUT': '86400', 04:47:23 INFO - 'USER': 'cltbld', 04:47:23 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:23 INFO - '_': '/tools/buildbot/bin/python'} 04:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:47:25 INFO - Installing collected packages: mozsystemmonitor 04:47:25 INFO - Running setup.py install for mozsystemmonitor 04:47:25 INFO - Successfully installed mozsystemmonitor 04:47:25 INFO - Cleaning up... 04:47:25 INFO - Return code: 0 04:47:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47:25 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')]} 04:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 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')]} 04:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47:25 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 04:47:25 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 04:47:25 INFO - Using env: {'DISPLAY': ':0', 04:47:25 INFO - 'HOME': '/home/cltbld', 04:47:25 INFO - 'LANG': 'en_US.UTF-8', 04:47:25 INFO - 'LANGUAGE': 'en_US:en', 04:47:25 INFO - 'LOGNAME': 'cltbld', 04:47:25 INFO - 'MAIL': '/var/mail/cltbld', 04:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:25 INFO - 'MOZ_NO_REMOTE': '1', 04:47:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:25 INFO - 'NO_EM_RESTART': '1', 04:47:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:25 INFO - 'PWD': '/builds/slave/test', 04:47:25 INFO - 'SHELL': '/bin/bash', 04:47:25 INFO - 'SHLVL': '1', 04:47:25 INFO - 'TERM': 'linux', 04:47:25 INFO - 'TMOUT': '86400', 04:47:25 INFO - 'USER': 'cltbld', 04:47:25 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:25 INFO - '_': '/tools/buildbot/bin/python'} 04:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:25 INFO - Downloading/unpacking blobuploader==1.2.4 04:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:27 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47:27 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 04:47:27 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 04:47:27 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 04:47:27 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 04:47:27 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 04:47:27 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 04:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:28 INFO - Downloading docopt-0.6.1.tar.gz 04:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:28 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:28 INFO - Running setup.py install for blobuploader 04:47:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:28 INFO - Running setup.py install for requests 04:47:29 INFO - Running setup.py install for docopt 04:47:29 INFO - Successfully installed blobuploader requests docopt 04:47:29 INFO - Cleaning up... 04:47:29 INFO - Return code: 0 04:47:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:29 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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47:29 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 04:47:29 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 04:47:29 INFO - Using env: {'DISPLAY': ':0', 04:47:29 INFO - 'HOME': '/home/cltbld', 04:47:29 INFO - 'LANG': 'en_US.UTF-8', 04:47:29 INFO - 'LANGUAGE': 'en_US:en', 04:47:29 INFO - 'LOGNAME': 'cltbld', 04:47:29 INFO - 'MAIL': '/var/mail/cltbld', 04:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:29 INFO - 'MOZ_NO_REMOTE': '1', 04:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:29 INFO - 'NO_EM_RESTART': '1', 04:47:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:29 INFO - 'PWD': '/builds/slave/test', 04:47:29 INFO - 'SHELL': '/bin/bash', 04:47:29 INFO - 'SHLVL': '1', 04:47:29 INFO - 'TERM': 'linux', 04:47:29 INFO - 'TMOUT': '86400', 04:47:29 INFO - 'USER': 'cltbld', 04:47:29 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:29 INFO - '_': '/tools/buildbot/bin/python'} 04:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:29 INFO - Running setup.py (path:/tmp/pip-g_JxIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:29 INFO - Running setup.py (path:/tmp/pip-y9c5ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:29 INFO - Running setup.py (path:/tmp/pip-RAWPcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:29 INFO - Running setup.py (path:/tmp/pip-UjIT6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:29 INFO - Running setup.py (path:/tmp/pip-SQGoSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:29 INFO - Running setup.py (path:/tmp/pip-28WJz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:29 INFO - Running setup.py (path:/tmp/pip-Js5dXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:29 INFO - Running setup.py (path:/tmp/pip-BrEF1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:30 INFO - Running setup.py (path:/tmp/pip-nbQ2Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:30 INFO - Running setup.py (path:/tmp/pip-DupC7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:30 INFO - Running setup.py (path:/tmp/pip-Pe6oP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:30 INFO - Running setup.py (path:/tmp/pip-eAsHam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:30 INFO - Running setup.py (path:/tmp/pip-SCvGPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:30 INFO - Running setup.py (path:/tmp/pip-WLO8Iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:30 INFO - Running setup.py (path:/tmp/pip-1ZJM8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:30 INFO - Running setup.py (path:/tmp/pip-KD6Xfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:30 INFO - Running setup.py (path:/tmp/pip-OVkEpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:30 INFO - Running setup.py install for manifestparser 04:47:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:30 INFO - Running setup.py install for mozcrash 04:47:30 INFO - Running setup.py install for mozdebug 04:47:30 INFO - Running setup.py install for mozdevice 04:47:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Running setup.py install for mozfile 04:47:31 INFO - Running setup.py install for mozhttpd 04:47:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Running setup.py install for mozinfo 04:47:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Running setup.py install for mozInstall 04:47:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Running setup.py install for mozleak 04:47:31 INFO - Running setup.py install for mozlog 04:47:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Running setup.py install for moznetwork 04:47:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Running setup.py install for mozprocess 04:47:31 INFO - Running setup.py install for mozprofile 04:47:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:31 INFO - Running setup.py install for mozrunner 04:47:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:32 INFO - Running setup.py install for mozscreenshot 04:47:32 INFO - Running setup.py install for moztest 04:47:32 INFO - Running setup.py install for mozversion 04:47:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:32 INFO - Cleaning up... 04:47:32 INFO - Return code: 0 04:47:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:32 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')]} 04:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:32 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')]} 04:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:32 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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47:32 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 04:47:32 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 04:47:32 INFO - Using env: {'DISPLAY': ':0', 04:47:32 INFO - 'HOME': '/home/cltbld', 04:47:32 INFO - 'LANG': 'en_US.UTF-8', 04:47:32 INFO - 'LANGUAGE': 'en_US:en', 04:47:32 INFO - 'LOGNAME': 'cltbld', 04:47:32 INFO - 'MAIL': '/var/mail/cltbld', 04:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:32 INFO - 'MOZ_NO_REMOTE': '1', 04:47:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:32 INFO - 'NO_EM_RESTART': '1', 04:47:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:32 INFO - 'PWD': '/builds/slave/test', 04:47:32 INFO - 'SHELL': '/bin/bash', 04:47:32 INFO - 'SHLVL': '1', 04:47:32 INFO - 'TERM': 'linux', 04:47:32 INFO - 'TMOUT': '86400', 04:47:32 INFO - 'USER': 'cltbld', 04:47:32 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:32 INFO - '_': '/tools/buildbot/bin/python'} 04:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:32 INFO - Running setup.py (path:/tmp/pip-AXWV80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:32 INFO - Running setup.py (path:/tmp/pip-2CwFHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:32 INFO - Running setup.py (path:/tmp/pip-gmdD5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:32 INFO - Running setup.py (path:/tmp/pip-E8cEUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:32 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)) 04:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:32 INFO - Running setup.py (path:/tmp/pip-U6XeJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47: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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:33 INFO - Running setup.py (path:/tmp/pip-5HTLo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47: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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:33 INFO - Running setup.py (path:/tmp/pip-klNxNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:33 INFO - Running setup.py (path:/tmp/pip-ICOYMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:33 INFO - Running setup.py (path:/tmp/pip-smel7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:33 INFO - Running setup.py (path:/tmp/pip-UdxOW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:33 INFO - Running setup.py (path:/tmp/pip-GCOoFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:33 INFO - Running setup.py (path:/tmp/pip-qcMpRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:33 INFO - Running setup.py (path:/tmp/pip-9AJ_QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:33 INFO - Running setup.py (path:/tmp/pip-r7K1fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:33 INFO - Running setup.py (path:/tmp/pip-OKavBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:33 INFO - Running setup.py (path:/tmp/pip-4LlAe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:33 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)) 04:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:33 INFO - Running setup.py (path:/tmp/pip-1djsvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:33 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)) 04:47:33 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)) 04:47:33 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)) 04:47:33 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)) 04:47:33 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)) 04:47:33 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)) 04:47:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:33 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 04:47:33 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 04:47:33 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 04:47:33 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 04:47:33 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 04:47:33 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 04:47:35 INFO - Downloading blessings-1.6.tar.gz 04:47:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:35 INFO - Installing collected packages: blessings 04:47:35 INFO - Running setup.py install for blessings 04:47:35 INFO - Successfully installed blessings 04:47:35 INFO - Cleaning up... 04:47:36 INFO - Return code: 0 04:47:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:36 INFO - Reading from file tmpfile_stdout 04:47:36 INFO - Current package versions: 04:47:36 INFO - argparse == 1.2.1 04:47:36 INFO - blessings == 1.6 04:47:36 INFO - blobuploader == 1.2.4 04:47:36 INFO - docopt == 0.6.1 04:47:36 INFO - manifestparser == 1.1 04:47:36 INFO - mozInstall == 1.12 04:47:36 INFO - mozcrash == 0.17 04:47:36 INFO - mozdebug == 0.1 04:47:36 INFO - mozdevice == 0.48 04:47:36 INFO - mozfile == 1.2 04:47:36 INFO - mozhttpd == 0.7 04:47:36 INFO - mozinfo == 0.9 04:47:36 INFO - mozleak == 0.1 04:47:36 INFO - mozlog == 3.1 04:47:36 INFO - moznetwork == 0.27 04:47:36 INFO - mozprocess == 0.22 04:47:36 INFO - mozprofile == 0.28 04:47:36 INFO - mozrunner == 6.11 04:47:36 INFO - mozscreenshot == 0.1 04:47:36 INFO - mozsystemmonitor == 0.0 04:47:36 INFO - moztest == 0.7 04:47:36 INFO - mozversion == 1.4 04:47:36 INFO - psutil == 3.1.1 04:47:36 INFO - requests == 1.2.3 04:47:36 INFO - wsgiref == 0.1.2 04:47:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:36 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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47:36 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 04:47:36 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 04:47:36 INFO - Using env: {'DISPLAY': ':0', 04:47:36 INFO - 'HOME': '/home/cltbld', 04:47:36 INFO - 'LANG': 'en_US.UTF-8', 04:47:36 INFO - 'LANGUAGE': 'en_US:en', 04:47:36 INFO - 'LOGNAME': 'cltbld', 04:47:36 INFO - 'MAIL': '/var/mail/cltbld', 04:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:36 INFO - 'MOZ_NO_REMOTE': '1', 04:47:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:36 INFO - 'NO_EM_RESTART': '1', 04:47:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:36 INFO - 'PWD': '/builds/slave/test', 04:47:36 INFO - 'SHELL': '/bin/bash', 04:47:36 INFO - 'SHLVL': '1', 04:47:36 INFO - 'TERM': 'linux', 04:47:36 INFO - 'TMOUT': '86400', 04:47:36 INFO - 'USER': 'cltbld', 04:47:36 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:36 INFO - '_': '/tools/buildbot/bin/python'} 04:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 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)) 04:47:36 INFO - Cleaning up... 04:47:36 INFO - Return code: 0 04:47:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:47:36 INFO - Using env: {'DISPLAY': ':0', 04:47:36 INFO - 'HOME': '/home/cltbld', 04:47:36 INFO - 'LANG': 'en_US.UTF-8', 04:47:36 INFO - 'LANGUAGE': 'en_US:en', 04:47:36 INFO - 'LOGNAME': 'cltbld', 04:47:36 INFO - 'MAIL': '/var/mail/cltbld', 04:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:36 INFO - 'MOZ_NO_REMOTE': '1', 04:47:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:36 INFO - 'NO_EM_RESTART': '1', 04:47:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:36 INFO - 'PWD': '/builds/slave/test', 04:47:36 INFO - 'SHELL': '/bin/bash', 04:47:36 INFO - 'SHLVL': '1', 04:47:36 INFO - 'TERM': 'linux', 04:47:36 INFO - 'TMOUT': '86400', 04:47:36 INFO - 'USER': 'cltbld', 04:47:36 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:36 INFO - '_': '/tools/buildbot/bin/python'} 04:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:36 INFO - Downloading/unpacking jsonschema 04:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:38 INFO - Downloading/unpacking functools32 (from jsonschema) 04:47:38 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 04:47:38 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 04:47:38 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 04:47:38 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 04:47:38 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 04:47:38 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 04:47:38 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:38 INFO - warning: no files found matching '*.txt' 04:47:38 INFO - no previously-included directories found matching 'build' 04:47:38 INFO - no previously-included directories found matching 'dist' 04:47:38 INFO - no previously-included directories found matching '.git*' 04:47:38 INFO - Installing collected packages: jsonschema, functools32 04:47:38 INFO - Running setup.py install for functools32 04:47:38 INFO - warning: no files found matching '*.txt' 04:47:38 INFO - no previously-included directories found matching 'build' 04:47:38 INFO - no previously-included directories found matching 'dist' 04:47:38 INFO - no previously-included directories found matching '.git*' 04:47:39 INFO - Successfully installed jsonschema functools32 04:47:39 INFO - Cleaning up... 04:47:39 INFO - Return code: 0 04:47:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x7fd139811f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x162d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', '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 04:47: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 04:47: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 04:47:39 INFO - Using env: {'DISPLAY': ':0', 04:47:39 INFO - 'HOME': '/home/cltbld', 04:47:39 INFO - 'LANG': 'en_US.UTF-8', 04:47:39 INFO - 'LANGUAGE': 'en_US:en', 04:47:39 INFO - 'LOGNAME': 'cltbld', 04:47:39 INFO - 'MAIL': '/var/mail/cltbld', 04:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:39 INFO - 'MOZ_NO_REMOTE': '1', 04:47:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:39 INFO - 'NO_EM_RESTART': '1', 04:47:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:39 INFO - 'PWD': '/builds/slave/test', 04:47:39 INFO - 'SHELL': '/bin/bash', 04:47:39 INFO - 'SHLVL': '1', 04:47:39 INFO - 'TERM': 'linux', 04:47:39 INFO - 'TMOUT': '86400', 04:47:39 INFO - 'USER': 'cltbld', 04:47:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:39 INFO - '_': '/tools/buildbot/bin/python'} 04:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:39 INFO - Downloading/unpacking flake8 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:47:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:47:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:47:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:41 INFO - no previously-included directories found matching 'docs/_build' 04:47:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:47:41 INFO - Running setup.py install for pep8 04:47:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:42 INFO - no previously-included directories found matching 'docs/_build' 04:47:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:47:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:47:42 INFO - Cleaning up... 04:47:42 INFO - Return code: 0 04:47:42 INFO - Running post-action listener: _resource_record_post_action 04:47:42 INFO - Running post-action listener: _start_resource_monitoring 04:47:42 INFO - Starting resource monitoring. 04:47:42 INFO - ##### 04:47:42 INFO - ##### Running install step. 04:47:42 INFO - ##### 04:47:42 INFO - Running pre-action listener: _resource_record_pre_action 04:47:42 INFO - Running main action method: install 04:47:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:42 INFO - Reading from file tmpfile_stdout 04:47:42 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:42 INFO - Reading from file tmpfile_stdout 04:47:42 INFO - Output received: 04:47:42 INFO - Usage: mozinstall [options] installer 04:47:42 INFO - Options: 04:47:42 INFO - -h, --help show this help message and exit 04:47:42 INFO - -d DEST, --destination=DEST 04:47:42 INFO - Directory to install application into. [default: 04:47:42 INFO - "/builds/slave/test"] 04:47:42 INFO - --app=APP Application being installed. [default: firefox] 04:47:42 INFO - mkdir: /builds/slave/test/build/application 04:47:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:47:55 INFO - Reading from file tmpfile_stdout 04:47:55 INFO - Output received: 04:47:55 INFO - /builds/slave/test/build/application/firefox/firefox 04:47:55 INFO - Running post-action listener: _resource_record_post_action 04:47:55 INFO - ##### 04:47:55 INFO - ##### Running run-tests step. 04:47:55 INFO - ##### 04:47:55 INFO - Running pre-action listener: _resource_record_pre_action 04:47:55 INFO - Running main action method: run_tests 04:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:47:55 INFO - Python 2.7.3 04:47:55 INFO - Return code: 0 04:47:55 INFO - grabbing minidump binary from tooltool 04:47: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')]} 04:47:55 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 0x16bc410>, '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 0x16bd310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:47:55 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 04:47:55 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 04:47:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:47:55 INFO - Return code: 0 04:47:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:47:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:47:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:47:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:47:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:47:56 INFO - Return code: 0 04:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 04:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 04:47:56 INFO - Using env: {'DISPLAY': ':0', 04:47:56 INFO - 'HOME': '/home/cltbld', 04:47:56 INFO - 'LANG': 'en_US.UTF-8', 04:47:56 INFO - 'LANGUAGE': 'en_US:en', 04:47:56 INFO - 'LOGNAME': 'cltbld', 04:47:56 INFO - 'MAIL': '/var/mail/cltbld', 04:47:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:47:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:56 INFO - 'MOZ_NO_REMOTE': '1', 04:47:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:47:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:56 INFO - 'NO_EM_RESTART': '1', 04:47:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:56 INFO - 'PWD': '/builds/slave/test', 04:47:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:47:56 INFO - 'SHELL': '/bin/bash', 04:47:56 INFO - 'SHLVL': '1', 04:47:56 INFO - 'TERM': 'linux', 04:47:56 INFO - 'TMOUT': '86400', 04:47:56 INFO - 'USER': 'cltbld', 04:47:56 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744', 04:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:56 INFO - '_': '/tools/buildbot/bin/python'} 04:47:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 04:47:56 INFO - mozversion application_buildid: 20160725015529 04:47:56 INFO - mozversion application_changeset: 59caf5ec41614bf5de43d45f41a1decee29a869d 04:47:56 INFO - mozversion application_display_name: Firefox 04:47:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:47:56 INFO - mozversion application_name: Firefox 04:47:56 INFO - mozversion application_remotingname: firefox 04:47:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:47:56 INFO - mozversion application_vendor: Mozilla 04:47:56 INFO - mozversion application_version: 48.0 04:47:56 INFO - mozversion platform_buildid: 20160725015529 04:47:56 INFO - mozversion platform_changeset: 59caf5ec41614bf5de43d45f41a1decee29a869d 04:47:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:47:56 INFO - mozversion platform_version: 48.0 04:47:56 INFO - using testdate: 1469447276 04:47:56 INFO - actual date: 1469447276 04:47:56 INFO - starting webserver on 'localhost:51164' 04:47:56 INFO - SUITE-START | Running 2 tests 04:47:56 INFO - TEST-START | dromaeo_css 04:47:56 INFO - operating with platform_type : linux_ 04:47:56 INFO - Initialising browser for dromaeo_css test... 04:47:56 INFO - TEST-INFO | started process 25284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpls2EE5/profile http://localhost:51164/getInfo.html) 04:48:00 INFO - PROCESS | 25284 | 04:48:00 INFO - PROCESS | 25284 | (process:25360): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:48:01 INFO - PROCESS | 25284 | __metrics Screen width/height:1600/1200 04:48:01 INFO - PROCESS | 25284 | colorDepth:24 04:48:01 INFO - PROCESS | 25284 | Browser inner width/height: 1024/697 04:48:01 INFO - PROCESS | 25284 | __metrics 04:48:01 ERROR - PROCESS | 25284 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 04:48:02 INFO - TEST-INFO | 25284: exit 0 04:48:02 INFO - Browser initialized. 04:48:02 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:02 INFO - TEST-INFO | started process 25411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpls2EE5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:04 INFO - PROCESS | 25411 | 04:48:04 INFO - PROCESS | 25411 | (process:25480): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:48:14 INFO - PROCESS | 25411 | RSS: Main: 150605824 04:48:14 INFO - PROCESS | 25411 | 04:49:03 INFO - PROCESS | 25411 | 1469447343869 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] 04:51:11 INFO - PROCESS | 25411 | Cycle 1(1): loaded http://localhost:51164/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51164/tests/dromaeo/cssquery-ext.html) 04:51:12 INFO - PROCESS | 25411 | RSS: Main: 154615808 04:51:12 INFO - PROCESS | 25411 | 04:54:14 INFO - PROCESS | 25411 | Cycle 1(1): loaded http://localhost:51164/tests/dromaeo/cssquery-ext.html (next: http://localhost:51164/tests/dromaeo/cssquery-jquery.html) 04:54:15 INFO - PROCESS | 25411 | RSS: Main: 152825856 04:54:15 INFO - PROCESS | 25411 | 04:57:16 INFO - PROCESS | 25411 | Cycle 1(1): loaded http://localhost:51164/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51164/tests/dromaeo/cssquery-mootools.html) 04:57:17 INFO - PROCESS | 25411 | RSS: Main: 152526848 04:57:17 INFO - PROCESS | 25411 | 05:00:18 INFO - PROCESS | 25411 | Cycle 1(1): loaded http://localhost:51164/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51164/tests/dromaeo/cssquery-prototype.html) 05:00:19 INFO - PROCESS | 25411 | RSS: Main: 154001408 05:00:19 INFO - PROCESS | 25411 | 05:03:21 INFO - PROCESS | 25411 | Cycle 1(1): loaded http://localhost:51164/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51164/tests/dromaeo/cssquery-yui.html) 05:03:22 INFO - PROCESS | 25411 | RSS: Main: 152469504 05:03:22 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | Cycle 1(1): loaded http://localhost:51164/tests/dromaeo/cssquery-yui.html (next: http://localhost:51164/tests/dromaeo/cssquery-dojo.html) 05:06:24 INFO - PROCESS | 25411 | RSS: Main: 156467200 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | __start_tp_report 05:06:24 INFO - PROCESS | 25411 | _x_x_mozilla_page_load 05:06:24 INFO - PROCESS | 25411 | _x_x_mozilla_page_load_details 05:06:24 INFO - PROCESS | 25411 | |i|pagename|runs| 05:06:24 INFO - PROCESS | 25411 | |0;dojo.html;3390.474476456149;3415.146213446639;3424.315136972606;3445.3453843769666;3469.0633528947183;5664.575156863236;5705.657660540371;5710.114932185513;5731.254936858208;5755.798547050854;5619;5622.887542249163;5720.370759216483;5733.025385684433;5756.366799652035;5159.226116082599;5212.452692467293;5258.6318957672975;5297.761600727977;5315.867103322409;5157.2006339017325;5197.298364720752;5233.3981592116825;5356.4107948125675;5401.972990135064;5279.102552566073;5302.1781623848365;5340.572754179656;5362.919556206641;5381.838544843641;5266.683998960075;5274.208868669692;5274.26160337553;5274.340707411574;5296.3379577552805;5051.747412629354;5158;5161.303224064778;5174.741262936838;5253.921191182136;5599.412061733541;5610.7194640268235;5618.606697531174;5633.746481408347;5657.886842263131;6113.541484388688;6128.96935515324;6138.232720909887;6141.754329826802;6142.877142457126;6035.456808887177;6113.419225173646;6176.351483094255;6178.660173690445;6192.969035154795;5768.480836724673;5769.105788602773;5770.16332631766;5779.2775903012125;5790.305163380379;6249.375062493805;6268.968655156695;6271.811845644638;6297.244330680256;6303.086052522451;5944.64332140073;5975.223220981245;6014.639121652627;6072.757089716361;6095.3904609538695;6137.202163718688;6152.169457123231;6159.168512250879;6163.907541386883;6179.0422484514975;6057.636541807417;6115.052166914135;6125.724342404603;6133.601315914451;6135.723892424851;5947.04847224442;6073.4837538808815;6143.450468055707;6148.600340977822;6164.4143806338325;4644.651651126179;4648.5119062498625;4652.418447694038;4672.369230153954;4674.859754207379;4105.97947010263;4154.91690166195;4159.771212583337;4169.207850508394;4189.120284740238;5745.88508229833;5776.364599894008;5836.241288632451;5961.403859614091;5980.880382392328;6134.325224225332;6171.7531298748445;6186.319504854462;6191.349908259658;6200.224971878515;5764.250647415809;5879.412058794172;5887.941120588825;5892.410758924073;5915.911261331084;5212.452692467312;5266.394364648115;5295.338082739658;5308.654937429054;5337.279467271868;6365.586236894587;6368.617882927039;6369.235691716931;6386.489080873519;6402.839929001719;5975.880482390241;5979.402059793986;6066.332703402534;6074.635521868702;6077.574569780073;6376.74493020274;6451.580647257913;6456.451201647913;6465.191851018622;6467.514936379696;5986.102084687332;6069.36271691466;6075.088736689532;6088.51291896656;6089.939100608937;5920.526357891445;6050.848728781815;6140.903050468011;6161.691915404302;6186.597871138361;5961.642301461926;6002.459778619946;6005.279366476051;6010.3088144863905;6012.669303188367;5965.940340596712;5981.371955944737;5982.162497250301;5982.282126144891;5998.850028749316;5682.943170568242;5859.150423188699;5880.411958804257;5933.703314834327;5994.910076348945;5908.822735317947;5964.373740757332;5978.910316345346;5993.880122397615;5995.820125396245;5872.119182122716;5875.735591898289;5878.470937615635;5889.911651325147;5893.086571581412 05:06:24 INFO - PROCESS | 25411 | |1;ext.html;672.3780503034694;678.7013713965855;685.6434653979934;728.8906664000399;745.8545583611194;39755;40508.189836203295;40783.18433631326;40871.79564102175;40884;44493;44969;45258.54741452593;45265.510396928155;45313.320300195526;8889.955550222256;9053.683121090764;9057.50183739894;9057.637694492245;9064.773380665503;8888.06675299094;8996.145366190234;8997.05530919254;9000.459972401644;9022.819543609125;9070.183683468487;9088.954555227214;9097.226735727438;9112.726618201465;9124.087591240888;9117.589708463134;9139.268858491305;9176.220021298197;9189.3108016899;9229.769255768593;9020.052894446055;9119.270458363315;9157.542122893828;9165.129312715282;9185.678501252423;44199.77900110511;44481.332780008;44781.77609111933;44872.3269150963;44909;3836.6163383661687;3922.17634296798;4053.7770422626745;4463.754493502856;4585.330919652282;33536;33796.83101584501;33882.83058584691;33912.49131263033;35381.93854184379;4394.62645675118;4531.093781243764;4557.521460246693;4572.177008138534;4669.929951050737;298891;300443;300648;300933;301849;39931.20137597232;58644;58721;58878.116828247614;58923.70538147282;38082.42876356857;38101.23797524034;38118.80940595335;38180.6181938177;38232.617673822904;17034.233459494106;17219.65560688779;17224.311027558757;17261.050642214548;17315.480535583953;14440.205788681724;14470.348834302482;14485;14522.49171279;14546.563603091925;9295.58169882357;9298;9388.014258502895;9439.433633981984;9452.763680907894;3615.9276814463565;3681.687056600219;3706.666400023957;3721.4789929409903;3723.1995121048562;1146.4038699876019;1235.6354875311804;1251.7746805574902;1258.3330834657645;1267.9619611411488;32792.180195494824;33329.16677083044;33640.66359336375;33735.325293493996;33858.98423047313;1250.6748245456065;1268;1295.009317871829;1303.8370203724535;1324.8410190777167;17353.913230433765;17646.470605881845;17745.46763597094;17825.910870445565;17922.731159032624;520121;891982;911503;923175;935033;63384;63975;64410.355896441375;64425.355746441935;64449.67775161094;1565.3190861975077;1570.9135997520018;1577.9526614201593;1581.6915701438108;1581.9288132034087;33282.33435331328;33374.332513350084;33553.32893342168;34566.48150277697;34811.47782783311;55538;56218.43781562296;56480;56636.43363566312;56708.43291567032;429482;429827;431463;431891;433513;31472.68527314789;31845;32043.519347209345;32267.677323227406;32438.351232974735;50831.237531436316;51755.74122129516;51840;52650.21024684556;53146.468535314154;43038.56961430346;43186.136277274905;43294.78352608217;43386.78306608447;44783.328250075625;42061.94845128774;42770.14459710726;43389.566104338555;43945.560544394146;44321.11357772891;43499.13001739884;43769.56230437655;44134.77932610316;44620.10759784851;45073.54926450693;37152.44271335986;37261.627383726904;37787.40188694174;38339;38408.423873642474;29297.41405171927;29513.704862951097;29711.25721856971;29880.85059574688;29888.85055574708 05:06:24 INFO - PROCESS | 25411 | |2;jquery.html;417.92686279901017;431.18721210518163;432.265149246281;436.6724956282788;438.95610438956106;18411;19218.807811921877;19320.03399830008;20270.898645506753;21303.25438609649;19399.806001939978;19950.90024549879;19984.700229496568;20048.198072077135;20219.191232350688;5205.947940520584;5419.024575576394;5462.399136095027;5662.207290979246;5700.173474846145;5587.161925711135;5624.128260119688;5637.379888212294;5695.74369153387;5933.970330148343;6430.3891130342545;6432.195975503062;6432.453241474457;6460.289368169498;6475.902861457082;6147.723352449106;6300.936990630081;6369.172007639024;6397.712102955355;6406.583572067801;5788;5804.796832110856;5817.825465236049;5825.242718446617;5830.7084645767545;14622.780658290134;14693.706125877423;14706.044107133002;14892.106473611619;22630.886845565667;2417.0452671194957;2500.7374225706403;2612.8040396970114;2628.487444948236;2794.720527947209;10275.434851083188;10432.895671043269;10598.576056957712;10733.926607339281;11129.332240065622;2075.2529089527757;2080.937571872846;2117.0579092303933;2123.734533183352;2142.5500644864596;72475.27524724738;79562;80021.59989200076;83156;83519.58240208821;11275.718107047389;11632.592859249886;11927.1650984432;11929.403529823474;11951.880481195076;16362.754558681629;16615.252313645917;16620.750688739678;16640.167991600614;16721.080340581386;9144.908550914406;9168.35821492489;9288.767780805534;9356;9366;9214.585343659552;9247.26021918245;9314.301427392973;9335;9336.299777516713;5046.495350464924;5054.342935418372;5074.3657042869645;5090.040334471255;5173.741312934339;2111.809937105668;2117.6611742121186;2138.6150492911424;2145.2813307541837;2169.8264138868853;1068.3589846092439;1113.204059097749;1115.4367044642565;1126.881677423875;1146.074892120974;19290.517737056405;19674.70487942682;19819.504512387306;20079.89560574153;20309.796902031085;1086.4024786367465;1090.5801266512292;1110.4059328259343;1122.9887701123048;1173.1904985559936;11028.724281893017;11309.717257068474;11461.656150315504;11511.424428778695;11523.76952460946;495271;500715;510572.2356941032;516007;530743;79103.60448197722;86829.56585217033;94428.52785736165;96600;98049.50975245221;1122.4443900269418;1160.9129315301386;1163.7148898519918;1185.8102703567388;1194.3908606610692;17080.743788843425;17378.73931891048;17538;17557.561060973578;17641.382551610634;39611.60388396079;39656;40482.595174049064;41055.58944410637;41480.1703965913;56722;59599.40400595938;59712;60046;60278;8503.23470887623;8910.910890891008;8925.687600933936;9116.27069834425;9198.862017069883;10557.9472102639;13246.80129798073;13287.800682989957;13340;13524.39140238672;8793.636986267138;11499;11771;11882.76234475323;11983.700407489883;11165.330080195214;11444.771104578029;11545.191836571359;11761.294322340345;11814.46834892449;12253.69365765863;12406.875931240573;12477.126601138196;12545.184563003375;12587.622371328875;9028.09719028092;9062.365634405793;9137.908620913971;9308.348415610842;9322.161005509537;11594.94202528982;11864.11019173548;11870.82193767077;11894.107941904565;11966.64100076999 05:06:24 INFO - PROCESS | 25411 | |3;mootools.html;1586.3020271080727;1606.421688192251;1860.1350372076981;1940.320887689308;1960.5784756277405;7800.687972481094;8384.287335576468;8562.743117706463;8742.475451472916;8756.036835948049;8613.827723445527;8624.482531048141;8688.56557172142;8699.34754893384;8701.129887011295;2380.666706661069;2405.0980882169188;2406.542756876188;2409.8554086754853;2418.467937053845;2387.5821731197057;2398.1726304424947;2437.354101163184;2463.2117722328862;2468.987655061731;2433.8783060846977;2437.841540299875;2449.0938352809485;2459.7786199242064;2475.084218839032;2403.110848985869;2420.8305418620703;2423.5274121546217;2437.4881274932195;2438.5976313908186;2382.2376839411395;2408.12103582193;2416.6495858100475;2431.051889762994;2437.695288088989;8959.238464730506;8984.955075224581;8985.595648195847;8992.640294388217;9021.278297736231;5383.246345511631;5501.779928802883;5538.861528461781;5568.1091025435735;5573.498385145316;3416.8120753358558;3447.9482807757904;3482.251315967054;3488.494168345601;3500.6674365935196;1095.7315457712832;1099.7910397024539;1118.7482816366335;1130.2314426190187;1141.2809929744208;12754.489820407172;12770.680732981657;12772.425240864184;12778.936105319506;12835.422405991752;4734.311428923684;4780.067886762117;4791.520847915181;4850.393700787402;4876.341693871353;10642.776080750658;10744.946275268574;10754.73113172177;10754.946225268981;10777.08394786452;5506.944930550643;5539.307586551681;5547.223388725582;5547.472990065938;5550.306211723535;4834.105690447279;4986.626003049713;4990.17662085759;5030.496950304941;5054.166062599704;3834.4631751554803;3835.6931445484292;3858.6334298241622;3920;3929.390944403622;1176.2589568571916;1180.527788884453;1180.5808937826948;1182.5920057580122;1185.3539820797687;978.882534095913;1049.071571659087;1062.5962134388883;1077.757504561483;1087.7117563845586;6051.273847138372;6130.1724267224245;6192.133101365813;6226;6256;1055.6727414501377;1080.9081228095542;1082.832161015044;1083.4745148602922;1112.3381587955155;4310.698251122454;4384.934225986613;4390.100029493946;4430.136123455895;4501.189785838515;10401;10828.891711082946;10829.566817327219;10841.512131954081;10843.83734243987;10644.680659580225;11019.118470522339;11022;11022.338659680285;11030.834537481944;8051.436399452099;8359.038710548242;8389.370797190235;8392.118827523143;8444.282236009885;6164.630122192682;6217.502599791916;6269.341719119427;6336.663336663337;6387.648679322496;9534.904650953678;9719.562619682272;9794.216462682825;9815.067568580973;9831.410115393099;9661.323707340418;9734.172595329477;9989.500524973577;10052.246081543768;10066.647667331901;7638.579878106758;7866.213378662088;7884.408669349937;8000.759977200693;8181.345492360478;8489.066202717573;8627.482351058956;8683.348748843735;8720.476771393736;8743.868841967249;7617.390608751176;7629.580373079534;7666.731664391719;7805.063392392949;7896.407769417202;7596.658150383136;7618.923810761821;7621.733239336597;7718.652660630173;7773.805654858674;7029.156501219886;7080.150381954199;7105.75129870452;7107.75122870697;7141.285871412817;5062.696238225718;5077.644564880571;5210.27056212123;5229;5246.737663116753;5490.835274941758;5495.505404513613;5590.105583106683;5651.491365777099;5663.235463212413 05:06:24 INFO - PROCESS | 25411 | |4;prototype.html;354.42936871636675;359.7481762766064;360.5403111033433;367.0713095867456;367.58830110276494;6475.320091390407;6476.805695829133;6496.992966090252;6591.4067733904;6626.370494802992;6517.152770139875;6527.911068458084;6557.868842623145;6577.9013314800195;6584.572002819813;5010.574101201393;5039.72281524516;5045.394552653668;5055.898882022376;5062.468440813716;5053.646244762868;5055.797768089272;5056.898862022757;5058.190689489682;5063.189889617662;5009.44896061433;5012.398512178525;5017.122003649347;5022.547970682638;5027.622928280393;4728.361671174382;4868.245421959602;4993.350864387643;5009.524095210949;5019.949800501985;4234.640055595278;4237.300845360512;4241.681873859442;4243.893902652459;4274.807633656493;6243.5338041473815;6243.968780156116;6251.218597675291;6251.656158911258;6275.184226050585;5725.5419566435;5737.311522617271;5740.856478588069;5742.626729262584;5744.82765517031;6879.793606191823;6882;6883.3116688331265;6892.414144797698;6892.620905850176;4606.147862645421;4756.833510827105;4762.523747625244;4768.427788665383;4778.4265888093305;7135.286471352875;7141.500094993353;7161.283871612849;7197.136343638745;7213.098362704662;4798.592119669797;4836;4842.6368022398465;4843.854684359475;4850;4910.21436570147;4911.68074075184;4912.729799861042;4916.680415772963;4929.2359684248995;5698.6580805151825;5700.401457846866;5703.515201207861;5707.315122185281;5707.800226992035;6751.898721519181;6851.520393572502;6901.240863505011;6914.377706006484;6921.169459664872;1698.651776385838;1715.6740219358283;1728.3432295727544;1728.7147620642704;1731.7921849378156;1116.263266244275;1116.6873275482878;1124.8031594470935;1138.5218208352512;1145.6734830573246;6639.734410623618;6669.7665581704405;6674.699638516181;6680.365365290289;6680.966595166993;7027.508074434741;7053.153621565445;7075.2570980047385;7093.290670932865;7109.715611375487;6593;6613.338666133444;6633.767818126439;6683.431908287753;6730.798076057726;3815.389537673959;3842.7310088293552;3846.1346197105427;3847.634474724897;3853.0560012797046;7249.927500724925;7322.450816188807;7424.071991001125;7441;7452.06849143857;7243.963780181065;7266.96366518164;7320;7325.120985481776;7345.632718364061;7528.5859277739155;7549.358304544176;7553.395728341719;7558.433117516208;7566.394688424913;6956.826079348057;7142.9285707144345;7175.354218120394;7199.49603527769;7205.855882882208;7080.114985626797;7105.147382314155;7116.679749411393;7118.608476533634;7131.572105673677;7513.060867391576;7515.962420187864;7520.623968801428;7523.796192609157;7560.243975602396;4990.900181996267;5035.320231768665;5048;5050.393952725696;5062.164742817394;7008.299170082951;7093.219745828058;7099.680514376969;7111.893321600283;7124.323189297009;6881.862362752817;6888.242293347627;6900.7929762107215;6917;6919.930800691928;6847.863042739018;6898.517103802887;6902.620355880274;6904.896426553706;6921.93078069233;6784.185897692111;6784.864302714017;6789.864202716017;6795.93204067953;6796.72813087471;6827.146606674166;6959.1996920352785;6962.930370696228;6967.7212911482975;6982.615956122462;6772.830679233059;6781.084553585203;6783.084283621648;6796.2524122347495;6801.251862295243 05:06:24 INFO - PROCESS | 25411 | |5;yui.html;819.9877001844973;881.6737807011407;920.4201353147516;925.7963248085423;947.2706016367401;13657.931710341447;13746.931265343672;13811.447542098304;13888.722225555483;13925.442982280696;12249.510019599227;14165.78751318731;14203.005789594732;14224.502142425017;14261.429542818274;899.7480705402481;924.3806649544821;926.6293482606944;926.7497775600584;929.2101713543501;917.6512925088457;918.1782304837167;924.2652091587204;925.9907400926005;928.6610387208688;963.7012526116927;964.0263334032608;964.0985678390692;967.2455484721928;968.1722127580888;958.5830163878666;958.9808203835919;960.0351646595162;960.3901522533207;965.4159233663673;715.9498835081546;716.373173473211;717.0141056047934;717.2827172827173;719.2052781676225;13897.235652039133;13910.652233694138;13959.860401395958;13975.440982360693;13987.860121398757;14996.95021348506;15155.848441515664;15265.160416177107;15275.465358712503;15404.614884627861;11963;12059.155859089868;12140.514379424812;12177.817332740016;12227.572034978733;4056.5740597237454;4070.450489183952;4121.814518346651;4134.37984302354;4151.190517849021;52107;52614.47385526172;52762;53010.73494632541;53117;11580.131490138274;11625.59310424131;11715.882841171479;11993.340366279765;12166.756664866652;21339.67990480144;21411.67882481764;21465.03407346673;21708.131674732835;21804.89097554534;8100.108988011314;8505.277051450696;8507.829843403097;8544.017437994586;8628;4828.179209534387;4846.539578740014;4884.267359895973;4897.706137631753;4984.501549844987;3643;3680.9079773005888;3698.593154752975;3726.552813662324;3732.7760334380023;648.1379764912655;652.7323797243107;662.4137638190236;666.0508774995612;668.0547025958215;830.9044459887069;835.8035861572525;840.9663613455516;860.8665656823202;862.5902696219246;9784.55969481375;9980.900190998142;10057.245706572035;10122.898771012196;10204.795904081877;781.5349866829227;815.6248125862052;818.2431251092715;831.3681601982537;842.6839935024366;4682.367880336179;4694.765261736899;4699.812007519729;4895.632827537949;4905.411350637875;61673.07490387648;62973.370266297665;63166;63270.36729632645;64068;53097.469025309256;53543.73228133834;54167.45832541624;54351.45648543543;55592.16611750827;49699;50506.74746626243;50542;50553.24170137451;50842.74578627083;9049.230815380766;9213.953930230575;9250.629974800933;9383.436993780395;9454.95272523633;37477;37576.81211593925;38194.236115278094;38331.616683832806;38476.73200658454;45884.31173532333;46466.53533464622;47491;48795.75602122109;48841;11956.461959211681;12004.639860804189;12119.81820272679;12152.878471215176;12191;12623.242605443702;12729.490820367451;12758.106932514635;12896.48414063427;13100.737985240052;11339.829902551302;11846.170768046153;12057.095717821023;12230.32733199647;12321.322327272086;12209.267443953391;12213.084018698455;12272.509099635914;12451.564195253122;12643.367831608199;11615.303081815118;12145.271283723005;12175;12197.268163910194;12400.069994750249;10008.699739007841;10087.243456740627;10252.794944101226;10297;10606.840897386699;10218.846717299395;10465.790684186426;10480.58431399895;10537.89462105369;10576.41829699374 05:06:24 INFO - PROCESS | 25411 | __end_tp_report 05:06:24 INFO - PROCESS | 25411 | __start_cc_report 05:06:24 INFO - PROCESS | 25411 | _x_x_mozilla_cycle_collect,313 05:06:24 INFO - PROCESS | 25411 | __end_cc_report 05:06:24 INFO - PROCESS | 25411 | __startTimestamp1469448384864__endTimestamp 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | ------- Summary: start ------- 05:06:24 INFO - PROCESS | 25411 | Number of tests: 6 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | [#0] dojo.html Cycles:165 Average:5702.25 Median:5920.53 stddev:635.22 (10.7%) stddev-sans-first:610.73 05:06:24 INFO - PROCESS | 25411 | Values: 3390.5 3415.1 3424.3 3445.3 3469.1 5664.6 5705.7 5710.1 5731.3 5755.8 5619.0 5622.9 5720.4 5733.0 5756.4 5159.2 5212.5 5258.6 5297.8 5315.9 5157.2 5197.3 5233.4 5356.4 5402.0 5279.1 5302.2 5340.6 5362.9 5381.8 5266.7 5274.2 5274.3 5274.3 5296.3 5051.7 5158.0 5161.3 5174.7 5253.9 5599.4 5610.7 5618.6 5633.7 5657.9 6113.5 6129.0 6138.2 6141.8 6142.9 6035.5 6113.4 6176.4 6178.7 6193.0 5768.5 5769.1 5770.2 5779.3 5790.3 6249.4 6269.0 6271.8 6297.2 6303.1 5944.6 5975.2 6014.6 6072.8 6095.4 6137.2 6152.2 6159.2 6163.9 6179.0 6057.6 6115.1 6125.7 6133.6 6135.7 5947.0 6073.5 6143.5 6148.6 6164.4 4644.7 4648.5 4652.4 4672.4 4674.9 4106.0 4154.9 4159.8 4169.2 4189.1 5745.9 5776.4 5836.2 5961.4 5980.9 6134.3 6171.8 6186.3 6191.3 6200.2 5764.3 5879.4 5887.9 5892.4 5915.9 5212.5 5266.4 5295.3 5308.7 5337.3 6365.6 6368.6 6369.2 6386.5 6402.8 5975.9 5979.4 6066.3 6074.6 6077.6 6376.7 6451.6 6456.5 6465.2 6467.5 5986.1 6069.4 6075.1 6088.5 6089.9 5920.5 6050.8 6140.9 6161.7 6186.6 5961.6 6002.5 6005.3 6010.3 6012.7 5965.9 5981.4 5982.2 5982.3 5998.9 5682.9 5859.2 5880.4 5933.7 5994.9 5908.8 5964.4 5978.9 5993.9 5995.8 5872.1 5875.7 5878.5 5889.9 5893.1 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | [#1] ext.html Cycles:180 Average:67151.87 Median:237538.57 stddev:156135.48 (65.7%) stddev-sans-first:156493.69 05:06:24 INFO - PROCESS | 25411 | Values: 672.4 678.7 685.6 728.9 745.9 39755.0 40508.2 40783.2 40871.8 40884.0 44493.0 44969.0 45258.5 45265.5 45313.3 8890.0 9053.7 9057.5 9057.6 9064.8 8888.1 8996.1 8997.1 9000.5 9022.8 9070.2 9089.0 9097.2 9112.7 9124.1 9117.6 9139.3 9176.2 9189.3 9229.8 9020.1 9119.3 9157.5 9165.1 9185.7 44199.8 44481.3 44781.8 44872.3 44909.0 3836.6 3922.2 4053.8 4463.8 4585.3 33536.0 33796.8 33882.8 33912.5 35381.9 4394.6 4531.1 4557.5 4572.2 4669.9 298891.0 300443.0 300648.0 300933.0 301849.0 39931.2 58644.0 58721.0 58878.1 58923.7 38082.4 38101.2 38118.8 38180.6 38232.6 17034.2 17219.7 17224.3 17261.1 17315.5 14440.2 14470.3 14485.0 14522.5 14546.6 9295.6 9298.0 9388.0 9439.4 9452.8 3615.9 3681.7 3706.7 3721.5 3723.2 1146.4 1235.6 1251.8 1258.3 1268.0 32792.2 33329.2 33640.7 33735.3 33859.0 1250.7 1268.0 1295.0 1303.8 1324.8 17353.9 17646.5 17745.5 17825.9 17922.7 520121.0 891982.0 911503.0 923175.0 935033.0 63384.0 63975.0 64410.4 64425.4 64449.7 1565.3 1570.9 1578.0 1581.7 1581.9 33282.3 33374.3 33553.3 34566.5 34811.5 55538.0 56218.4 56480.0 56636.4 56708.4 429482.0 429827.0 431463.0 431891.0 433513.0 31472.7 31845.0 32043.5 32267.7 32438.4 50831.2 51755.7 51840.0 52650.2 53146.5 43038.6 43186.1 43294.8 43386.8 44783.3 42061.9 42770.1 43389.6 43945.6 44321.1 43499.1 43769.6 44134.8 44620.1 45073.5 37152.4 37261.6 37787.4 38339.0 38408.4 29297.4 29513.7 29711.3 29880.9 29888.9 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | [#2] jquery.html Cycles:180 Average:29629.94 Median:2143.92 stddev:84045.39 (3920.2%) stddev-sans-first:84252.54 05:06:24 INFO - PROCESS | 25411 | Values: 417.9 431.2 432.3 436.7 439.0 18411.0 19218.8 19320.0 20270.9 21303.3 19399.8 19950.9 19984.7 20048.2 20219.2 5205.9 5419.0 5462.4 5662.2 5700.2 5587.2 5624.1 5637.4 5695.7 5934.0 6430.4 6432.2 6432.5 6460.3 6475.9 6147.7 6300.9 6369.2 6397.7 6406.6 5788.0 5804.8 5817.8 5825.2 5830.7 14622.8 14693.7 14706.0 14892.1 22630.9 2417.0 2500.7 2612.8 2628.5 2794.7 10275.4 10432.9 10598.6 10733.9 11129.3 2075.3 2080.9 2117.1 2123.7 2142.6 72475.3 79562.0 80021.6 83156.0 83519.6 11275.7 11632.6 11927.2 11929.4 11951.9 16362.8 16615.3 16620.8 16640.2 16721.1 9144.9 9168.4 9288.8 9356.0 9366.0 9214.6 9247.3 9314.3 9335.0 9336.3 5046.5 5054.3 5074.4 5090.0 5173.7 2111.8 2117.7 2138.6 2145.3 2169.8 1068.4 1113.2 1115.4 1126.9 1146.1 19290.5 19674.7 19819.5 20079.9 20309.8 1086.4 1090.6 1110.4 1123.0 1173.2 11028.7 11309.7 11461.7 11511.4 11523.8 495271.0 500715.0 510572.2 516007.0 530743.0 79103.6 86829.6 94428.5 96600.0 98049.5 1122.4 1160.9 1163.7 1185.8 1194.4 17080.7 17378.7 17538.0 17557.6 17641.4 39611.6 39656.0 40482.6 41055.6 41480.2 56722.0 59599.4 59712.0 60046.0 60278.0 8503.2 8910.9 8925.7 9116.3 9198.9 10557.9 13246.8 13287.8 13340.0 13524.4 8793.6 11499.0 11771.0 11882.8 11983.7 11165.3 11444.8 11545.2 11761.3 11814.5 12253.7 12406.9 12477.1 12545.2 12587.6 9028.1 9062.4 9137.9 9308.3 9322.2 11594.9 11864.1 11870.8 11894.1 11966.6 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | [#3] mootools.html Cycles:180 Average:5901.55 Median:4863.37 stddev:3277.17 (67.4%) stddev-sans-first:3270.32 05:06:24 INFO - PROCESS | 25411 | Values: 1586.3 1606.4 1860.1 1940.3 1960.6 7800.7 8384.3 8562.7 8742.5 8756.0 8613.8 8624.5 8688.6 8699.3 8701.1 2380.7 2405.1 2406.5 2409.9 2418.5 2387.6 2398.2 2437.4 2463.2 2469.0 2433.9 2437.8 2449.1 2459.8 2475.1 2403.1 2420.8 2423.5 2437.5 2438.6 2382.2 2408.1 2416.6 2431.1 2437.7 8959.2 8985.0 8985.6 8992.6 9021.3 5383.2 5501.8 5538.9 5568.1 5573.5 3416.8 3447.9 3482.3 3488.5 3500.7 1095.7 1099.8 1118.7 1130.2 1141.3 12754.5 12770.7 12772.4 12778.9 12835.4 4734.3 4780.1 4791.5 4850.4 4876.3 10642.8 10744.9 10754.7 10754.9 10777.1 5506.9 5539.3 5547.2 5547.5 5550.3 4834.1 4986.6 4990.2 5030.5 5054.2 3834.5 3835.7 3858.6 3920.0 3929.4 1176.3 1180.5 1180.6 1182.6 1185.4 978.9 1049.1 1062.6 1077.8 1087.7 6051.3 6130.2 6192.1 6226.0 6256.0 1055.7 1080.9 1082.8 1083.5 1112.3 4310.7 4384.9 4390.1 4430.1 4501.2 10401.0 10828.9 10829.6 10841.5 10843.8 10644.7 11019.1 11022.0 11022.3 11030.8 8051.4 8359.0 8389.4 8392.1 8444.3 6164.6 6217.5 6269.3 6336.7 6387.6 9534.9 9719.6 9794.2 9815.1 9831.4 9661.3 9734.2 9989.5 10052.2 10066.6 7638.6 7866.2 7884.4 8000.8 8181.3 8489.1 8627.5 8683.3 8720.5 8743.9 7617.4 7629.6 7666.7 7805.1 7896.4 7596.7 7618.9 7621.7 7718.7 7773.8 7029.2 7080.2 7105.8 7107.8 7141.3 5062.7 5077.6 5210.3 5229.0 5246.7 5490.8 5495.5 5590.1 5651.5 5663.2 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | [#4] prototype.html Cycles:180 Average:5787.87 Median:6619.85 stddev:1756.92 (26.5%) stddev-sans-first:1713.87 05:06:24 INFO - PROCESS | 25411 | Values: 354.4 359.7 360.5 367.1 367.6 6475.3 6476.8 6497.0 6591.4 6626.4 6517.2 6527.9 6557.9 6577.9 6584.6 5010.6 5039.7 5045.4 5055.9 5062.5 5053.6 5055.8 5056.9 5058.2 5063.2 5009.4 5012.4 5017.1 5022.5 5027.6 4728.4 4868.2 4993.4 5009.5 5019.9 4234.6 4237.3 4241.7 4243.9 4274.8 6243.5 6244.0 6251.2 6251.7 6275.2 5725.5 5737.3 5740.9 5742.6 5744.8 6879.8 6882.0 6883.3 6892.4 6892.6 4606.1 4756.8 4762.5 4768.4 4778.4 7135.3 7141.5 7161.3 7197.1 7213.1 4798.6 4836.0 4842.6 4843.9 4850.0 4910.2 4911.7 4912.7 4916.7 4929.2 5698.7 5700.4 5703.5 5707.3 5707.8 6751.9 6851.5 6901.2 6914.4 6921.2 1698.7 1715.7 1728.3 1728.7 1731.8 1116.3 1116.7 1124.8 1138.5 1145.7 6639.7 6669.8 6674.7 6680.4 6681.0 7027.5 7053.2 7075.3 7093.3 7109.7 6593.0 6613.3 6633.8 6683.4 6730.8 3815.4 3842.7 3846.1 3847.6 3853.1 7249.9 7322.5 7424.1 7441.0 7452.1 7244.0 7267.0 7320.0 7325.1 7345.6 7528.6 7549.4 7553.4 7558.4 7566.4 6956.8 7142.9 7175.4 7199.5 7205.9 7080.1 7105.1 7116.7 7118.6 7131.6 7513.1 7516.0 7520.6 7523.8 7560.2 4990.9 5035.3 5048.0 5050.4 5062.2 7008.3 7093.2 7099.7 7111.9 7124.3 6881.9 6888.2 6900.8 6917.0 6919.9 6847.9 6898.5 6902.6 6904.9 6921.9 6784.2 6784.9 6789.9 6795.9 6796.7 6827.1 6959.2 6962.9 6967.7 6982.6 6772.8 6781.1 6783.1 6796.3 6801.3 05:06:24 INFO - PROCESS | 25411 | 05:06:24 INFO - PROCESS | 25411 | [#5] yui.html Cycles:180 Average:15085.52 Median:4688.57 stddev:17213.47 (367.1%) stddev-sans-first:17228.42 05:06:24 INFO - PROCESS | 25411 | Values: 820.0 881.7 920.4 925.8 947.3 13657.9 13746.9 13811.4 13888.7 13925.4 12249.5 14165.8 14203.0 14224.5 14261.4 899.7 924.4 926.6 926.7 929.2 917.7 918.2 924.3 926.0 928.7 963.7 964.0 964.1 967.2 968.2 958.6 959.0 960.0 960.4 965.4 715.9 716.4 717.0 717.3 719.2 13897.2 13910.7 13959.9 13975.4 13987.9 14997.0 15155.8 15265.2 15275.5 15404.6 11963.0 12059.2 12140.5 12177.8 12227.6 4056.6 4070.5 4121.8 4134.4 4151.2 52107.0 52614.5 52762.0 53010.7 53117.0 11580.1 11625.6 11715.9 11993.3 12166.8 21339.7 21411.7 21465.0 21708.1 21804.9 8100.1 8505.3 8507.8 8544.0 8628.0 4828.2 4846.5 4884.3 4897.7 4984.5 3643.0 3680.9 3698.6 3726.6 3732.8 648.1 652.7 662.4 666.1 668.1 830.9 835.8 841.0 860.9 862.6 9784.6 9980.9 10057.2 10122.9 10204.8 781.5 815.6 818.2 831.4 842.7 4682.4 4694.8 4699.8 4895.6 4905.4 61673.1 62973.4 63166.0 63270.4 64068.0 53097.5 53543.7 54167.5 54351.5 55592.2 49699.0 50506.7 50542.0 50553.2 50842.7 9049.2 9214.0 9250.6 9383.4 9455.0 37477.0 37576.8 38194.2 38331.6 38476.7 45884.3 46466.5 47491.0 48795.8 48841.0 11956.5 12004.6 12119.8 12152.9 12191.0 12623.2 12729.5 12758.1 12896.5 13100.7 11339.8 11846.2 12057.1 12230.3 12321.3 12209.3 12213.1 12272.5 12451.6 12643.4 11615.3 12145.3 12175.0 12197.3 12400.1 10008.7 10087.2 10252.8 10297.0 10606.8 10218.8 10465.8 10480.6 10537.9 10576.4 05:06:24 INFO - PROCESS | 25411 | -------- Summary: end -------- 05:06:24 INFO - PROCESS | 25411 | 05:06:25 INFO - TEST-INFO | 25411: exit 0 05:06:25 INFO - TEST-OK | dromaeo_css | took 1108612ms 05:06:25 INFO - TEST-START | kraken 05:06:25 INFO - operating with platform_type : linux_ 05:06:25 INFO - Initialising browser for kraken test... 05:06:25 INFO - TEST-INFO | started process 25653 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUziL01/profile http://localhost:51164/getInfo.html) 05:06:28 INFO - PROCESS | 25653 | 05:06:28 INFO - PROCESS | 25653 | (process:25731): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:06:29 INFO - PROCESS | 25653 | __metrics Screen width/height:1600/1200 05:06:29 INFO - PROCESS | 25653 | colorDepth:24 05:06:29 INFO - PROCESS | 25653 | Browser inner width/height: 1024/697 05:06:29 INFO - PROCESS | 25653 | __metrics 05:06:30 ERROR - PROCESS | 25653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 05:06:30 INFO - TEST-INFO | 25653: exit 0 05:06:30 INFO - Browser initialized. 05:06:30 INFO - Running cycle 1/1 for kraken test... 05:06:30 INFO - TEST-INFO | started process 25781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUziL01/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:32 INFO - PROCESS | 25781 | 05:06:32 INFO - PROCESS | 25781 | (process:25851): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:06:42 INFO - PROCESS | 25781 | RSS: Main: 150441984 05:06:42 INFO - PROCESS | 25781 | 05:06:45 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51164/tests/kraken/driver.html?testName=audio-beat-detection) 05:06:45 INFO - PROCESS | 25781 | RSS: Main: 156381184 05:06:45 INFO - PROCESS | 25781 | 05:06:48 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51164/tests/kraken/driver.html?testName=audio-dft) 05:06:49 INFO - PROCESS | 25781 | RSS: Main: 156213248 05:06:49 INFO - PROCESS | 25781 | 05:06:52 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51164/tests/kraken/driver.html?testName=audio-fft) 05:06:52 INFO - PROCESS | 25781 | RSS: Main: 156205056 05:06:52 INFO - PROCESS | 25781 | 05:06:54 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51164/tests/kraken/driver.html?testName=audio-oscillator) 05:06:55 INFO - PROCESS | 25781 | RSS: Main: 156364800 05:06:55 INFO - PROCESS | 25781 | 05:06:57 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51164/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:06:58 INFO - PROCESS | 25781 | RSS: Main: 156151808 05:06:58 INFO - PROCESS | 25781 | 05:07:01 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51164/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:01 INFO - PROCESS | 25781 | RSS: Main: 156479488 05:07:01 INFO - PROCESS | 25781 | 05:07:05 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51164/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:05 INFO - PROCESS | 25781 | RSS: Main: 156409856 05:07:05 INFO - PROCESS | 25781 | 05:07:08 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51164/tests/kraken/driver.html?testName=json-parse-financial) 05:07:09 INFO - PROCESS | 25781 | RSS: Main: 156504064 05:07:09 INFO - PROCESS | 25781 | 05:07:10 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51164/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:11 INFO - PROCESS | 25781 | RSS: Main: 155918336 05:07:11 INFO - PROCESS | 25781 | 05:07:13 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:13 INFO - PROCESS | 25781 | RSS: Main: 156807168 05:07:13 INFO - PROCESS | 25781 | 05:07:15 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:16 INFO - PROCESS | 25781 | RSS: Main: 156925952 05:07:16 INFO - PROCESS | 25781 | 05:07:18 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:19 INFO - PROCESS | 25781 | RSS: Main: 157122560 05:07:19 INFO - PROCESS | 25781 | 05:07:22 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:22 INFO - PROCESS | 25781 | RSS: Main: 157122560 05:07:22 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | Cycle 1(1): loaded http://localhost:51164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51164/tests/kraken/driver.html?testName=ai-astar) 05:07:24 INFO - PROCESS | 25781 | RSS: Main: 158601216 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | __start_tp_report 05:07:24 INFO - PROCESS | 25781 | _x_x_mozilla_page_load 05:07:24 INFO - PROCESS | 25781 | _x_x_mozilla_page_load_details 05:07:24 INFO - PROCESS | 25781 | |i|pagename|runs| 05:07:24 INFO - PROCESS | 25781 | |0;ai-astar;110;111;101;110;112;111;101;109;110;112 05:07:24 INFO - PROCESS | 25781 | |1;audio-beat-detection;156;127;161;183;136;155;160;128;191;134 05:07:24 INFO - PROCESS | 25781 | |2;audio-dft;163;162;162;164;162;164;164;162;162;164 05:07:24 INFO - PROCESS | 25781 | |3;audio-fft;75;77;79;178;76;73;74;77;190;76 05:07:24 INFO - PROCESS | 25781 | |4;audio-oscillator;92;92;145;93;93;91;147;92;92;155 05:07:24 INFO - PROCESS | 25781 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;118;117;118 05:07:24 INFO - PROCESS | 25781 | |6;imaging-darkroom;149;147;146;147;147;147;146;148;147;147 05:07:24 INFO - PROCESS | 25781 | |7;imaging-desaturate;95;94;95;106;95;95;95;95;95;95 05:07:24 INFO - PROCESS | 25781 | |8;json-parse-financial;56;56;57;61;59;64;66;75;57;54 05:07:24 INFO - PROCESS | 25781 | |9;json-stringify-tinderbox;58;58;59;59;59;58;58;59;59;59 05:07:24 INFO - PROCESS | 25781 | |10;stanford-crypto-aes;74;75;86;74;83;74;74;73;75;75 05:07:24 INFO - PROCESS | 25781 | |11;stanford-crypto-ccm;116;126;126;120;121;112;122;116;134;132 05:07:24 INFO - PROCESS | 25781 | |12;stanford-crypto-pbkdf2;165;163;169;166;165;164;166;165;168;169 05:07:24 INFO - PROCESS | 25781 | |13;stanford-crypto-sha256-iterative;67;72;66;66;68;67;75;67;66;75 05:07:24 INFO - PROCESS | 25781 | __end_tp_report 05:07:24 INFO - PROCESS | 25781 | __start_cc_report 05:07:24 INFO - PROCESS | 25781 | _x_x_mozilla_cycle_collect,850 05:07:24 INFO - PROCESS | 25781 | __end_cc_report 05:07:24 INFO - PROCESS | 25781 | __startTimestamp1469448444776__endTimestamp 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | ------- Summary: start ------- 05:07:24 INFO - PROCESS | 25781 | Number of tests: 14 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.50 stddev:4.16 (3.8%) stddev-sans-first:4.39 05:07:24 INFO - PROCESS | 25781 | Values: 110.0 111.0 101.0 110.0 112.0 111.0 101.0 109.0 110.0 112.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#1] audio-beat-detection Cycles:10 Average:153.10 Median:158.00 stddev:22.16 (14.0%) stddev-sans-first:23.48 05:07:24 INFO - PROCESS | 25781 | Values: 156.0 127.0 161.0 183.0 136.0 155.0 160.0 128.0 191.0 134.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.50 stddev:0.99 (0.6%) stddev-sans-first:1.05 05:07:24 INFO - PROCESS | 25781 | Values: 163.0 162.0 162.0 164.0 162.0 164.0 164.0 162.0 162.0 164.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#3] audio-fft Cycles:10 Average:97.50 Median:76.00 stddev:45.71 (60.1%) stddev-sans-first:47.75 05:07:24 INFO - PROCESS | 25781 | Values: 75.0 77.0 79.0 178.0 76.0 73.0 74.0 77.0 190.0 76.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#4] audio-oscillator Cycles:10 Average:109.20 Median:92.00 stddev:27.58 (30.0%) stddev-sans-first:28.55 05:07:24 INFO - PROCESS | 25781 | Values: 92.0 92.0 145.0 93.0 93.0 91.0 147.0 92.0 92.0 155.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:07:24 INFO - PROCESS | 25781 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 117.0 118.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.60 05:07:24 INFO - PROCESS | 25781 | Values: 149.0 147.0 146.0 147.0 147.0 147.0 146.0 148.0 147.0 147.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:95.00 stddev:3.53 (3.7%) stddev-sans-first:3.72 05:07:24 INFO - PROCESS | 25781 | Values: 95.0 94.0 95.0 106.0 95.0 95.0 95.0 95.0 95.0 95.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:60.00 stddev:6.35 (10.6%) stddev-sans-first:6.52 05:07:24 INFO - PROCESS | 25781 | Values: 56.0 56.0 57.0 61.0 59.0 64.0 66.0 75.0 57.0 54.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.52 (0.9%) stddev-sans-first:0.50 05:07:24 INFO - PROCESS | 25781 | Values: 58.0 58.0 59.0 59.0 59.0 58.0 58.0 59.0 59.0 59.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:75.00 stddev:4.42 (5.9%) stddev-sans-first:4.61 05:07:24 INFO - PROCESS | 25781 | Values: 74.0 75.0 86.0 74.0 83.0 74.0 74.0 73.0 75.0 75.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:124.00 stddev:7.07 (5.7%) stddev-sans-first:7.10 05:07:24 INFO - PROCESS | 25781 | Values: 116.0 126.0 126.0 120.0 121.0 112.0 122.0 116.0 134.0 132.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.00 stddev:2.05 (1.2%) stddev-sans-first:2.15 05:07:24 INFO - PROCESS | 25781 | Values: 165.0 163.0 169.0 166.0 165.0 164.0 166.0 165.0 168.0 169.0 05:07:24 INFO - PROCESS | 25781 | 05:07:24 INFO - PROCESS | 25781 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:67.50 stddev:3.67 (5.4%) stddev-sans-first:3.82 05:07:24 INFO - PROCESS | 25781 | Values: 67.0 72.0 66.0 66.0 68.0 67.0 75.0 67.0 66.0 75.0 05:07:24 INFO - PROCESS | 25781 | -------- Summary: end -------- 05:07:24 INFO - PROCESS | 25781 | 05:07:25 INFO - TEST-INFO | 25781: exit 0 05:07:25 INFO - TEST-OK | kraken | took 60131ms 05:07:25 INFO - SUITE-END | took 1168s 05:07:25 INFO - Completed test suite (00:19:28) 05:07:25 INFO - javascript benchmark 05:07:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.474476456149, 3415.146213446639, 3424.315136972606, 3445.3453843769666, 3469.0633528947183, 5664.575156863236, 5705.657660540371, 5710.114932185513, 5731.254936858208, 5755.798547050854, 5619.0, 5622.887542249163, 5720.370759216483, 5733.025385684433, 5756.366799652035, 5159.226116082599, 5212.452692467293, 5258.6318957672975, 5297.761600727977, 5315.867103322409, 5157.2006339017325, 5197.298364720752, 5233.3981592116825, 5356.4107948125675, 5401.972990135064, 5279.102552566073, 5302.1781623848365, 5340.572754179656, 5362.919556206641, 5381.838544843641, 5266.683998960075, 5274.208868669692, 5274.26160337553, 5274.340707411574, 5296.3379577552805, 5051.747412629354, 5158.0, 5161.303224064778, 5174.741262936838, 5253.921191182136, 5599.412061733541, 5610.7194640268235, 5618.606697531174, 5633.746481408347, 5657.886842263131, 6113.541484388688, 6128.96935515324, 6138.232720909887, 6141.754329826802, 6142.877142457126, 6035.456808887177, 6113.419225173646, 6176.351483094255, 6178.660173690445, 6192.969035154795, 5768.480836724673, 5769.105788602773, 5770.16332631766, 5779.2775903012125, 5790.305163380379, 6249.375062493805, 6268.968655156695, 6271.811845644638, 6297.244330680256, 6303.086052522451, 5944.64332140073, 5975.223220981245, 6014.639121652627, 6072.757089716361, 6095.3904609538695, 6137.202163718688, 6152.169457123231, 6159.168512250879, 6163.907541386883, 6179.0422484514975, 6057.636541807417, 6115.052166914135, 6125.724342404603, 6133.601315914451, 6135.723892424851, 5947.04847224442, 6073.4837538808815, 6143.450468055707, 6148.600340977822, 6164.4143806338325, 4644.651651126179, 4648.5119062498625, 4652.418447694038, 4672.369230153954, 4674.859754207379, 4105.97947010263, 4154.91690166195, 4159.771212583337, 4169.207850508394, 4189.120284740238, 5745.88508229833, 5776.364599894008, 5836.241288632451, 5961.403859614091, 5980.880382392328, 6134.325224225332, 6171.7531298748445, 6186.319504854462, 6191.349908259658, 6200.224971878515, 5764.250647415809, 5879.412058794172, 5887.941120588825, 5892.410758924073, 5915.911261331084, 5212.452692467312, 5266.394364648115, 5295.338082739658, 5308.654937429054, 5337.279467271868, 6365.586236894587, 6368.617882927039, 6369.235691716931, 6386.489080873519, 6402.839929001719, 5975.880482390241, 5979.402059793986, 6066.332703402534, 6074.635521868702, 6077.574569780073, 6376.74493020274, 6451.580647257913, 6456.451201647913, 6465.191851018622, 6467.514936379696, 5986.102084687332, 6069.36271691466, 6075.088736689532, 6088.51291896656, 6089.939100608937, 5920.526357891445, 6050.848728781815, 6140.903050468011, 6161.691915404302, 6186.597871138361, 5961.642301461926, 6002.459778619946, 6005.279366476051, 6010.3088144863905, 6012.669303188367, 5965.940340596712, 5981.371955944737, 5982.162497250301, 5982.282126144891, 5998.850028749316, 5682.943170568242, 5859.150423188699, 5880.411958804257, 5933.703314834327, 5994.910076348945, 5908.822735317947, 5964.373740757332, 5978.910316345346, 5993.880122397615, 5995.820125396245, 5872.119182122716, 5875.735591898289, 5878.470937615635, 5889.911651325147, 5893.086571581412], "value": 5660.634931581556, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.3780503034694, 678.7013713965855, 685.6434653979934, 728.8906664000399, 745.8545583611194, 39755.0, 40508.189836203295, 40783.18433631326, 40871.79564102175, 40884.0, 44493.0, 44969.0, 45258.54741452593, 45265.510396928155, 45313.320300195526, 8889.955550222256, 9053.683121090764, 9057.50183739894, 9057.637694492245, 9064.773380665503, 8888.06675299094, 8996.145366190234, 8997.05530919254, 9000.459972401644, 9022.819543609125, 9070.183683468487, 9088.954555227214, 9097.226735727438, 9112.726618201465, 9124.087591240888, 9117.589708463134, 9139.268858491305, 9176.220021298197, 9189.3108016899, 9229.769255768593, 9020.052894446055, 9119.270458363315, 9157.542122893828, 9165.129312715282, 9185.678501252423, 44199.77900110511, 44481.332780008, 44781.77609111933, 44872.3269150963, 44909.0, 3836.6163383661687, 3922.17634296798, 4053.7770422626745, 4463.754493502856, 4585.330919652282, 33536.0, 33796.83101584501, 33882.83058584691, 33912.49131263033, 35381.93854184379, 4394.62645675118, 4531.093781243764, 4557.521460246693, 4572.177008138534, 4669.929951050737, 298891.0, 300443.0, 300648.0, 300933.0, 301849.0, 39931.20137597232, 58644.0, 58721.0, 58878.116828247614, 58923.70538147282, 38082.42876356857, 38101.23797524034, 38118.80940595335, 38180.6181938177, 38232.617673822904, 17034.233459494106, 17219.65560688779, 17224.311027558757, 17261.050642214548, 17315.480535583953, 14440.205788681724, 14470.348834302482, 14485.0, 14522.49171279, 14546.563603091925, 9295.58169882357, 9298.0, 9388.014258502895, 9439.433633981984, 9452.763680907894, 3615.9276814463565, 3681.687056600219, 3706.666400023957, 3721.4789929409903, 3723.1995121048562, 1146.4038699876019, 1235.6354875311804, 1251.7746805574902, 1258.3330834657645, 1267.9619611411488, 32792.180195494824, 33329.16677083044, 33640.66359336375, 33735.325293493996, 33858.98423047313, 1250.6748245456065, 1268.0, 1295.009317871829, 1303.8370203724535, 1324.8410190777167, 17353.913230433765, 17646.470605881845, 17745.46763597094, 17825.910870445565, 17922.731159032624, 520121.0, 891982.0, 911503.0, 923175.0, 935033.0, 63384.0, 63975.0, 64410.355896441375, 64425.355746441935, 64449.67775161094, 1565.3190861975077, 1570.9135997520018, 1577.9526614201593, 1581.6915701438108, 1581.9288132034087, 33282.33435331328, 33374.332513350084, 33553.32893342168, 34566.48150277697, 34811.47782783311, 55538.0, 56218.43781562296, 56480.0, 56636.43363566312, 56708.43291567032, 429482.0, 429827.0, 431463.0, 431891.0, 433513.0, 31472.68527314789, 31845.0, 32043.519347209345, 32267.677323227406, 32438.351232974735, 50831.237531436316, 51755.74122129516, 51840.0, 52650.21024684556, 53146.468535314154, 43038.56961430346, 43186.136277274905, 43294.78352608217, 43386.78306608447, 44783.328250075625, 42061.94845128774, 42770.14459710726, 43389.566104338555, 43945.560544394146, 44321.11357772891, 43499.13001739884, 43769.56230437655, 44134.77932610316, 44620.10759784851, 45073.54926450693, 37152.44271335986, 37261.627383726904, 37787.40188694174, 38339.0, 38408.423873642474, 29297.41405171927, 29513.704862951097, 29711.25721856971, 29880.85059574688, 29888.85055574708], "value": 20345.800233574817, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.92686279901017, 431.18721210518163, 432.265149246281, 436.6724956282788, 438.95610438956106, 18411.0, 19218.807811921877, 19320.03399830008, 20270.898645506753, 21303.25438609649, 19399.806001939978, 19950.90024549879, 19984.700229496568, 20048.198072077135, 20219.191232350688, 5205.947940520584, 5419.024575576394, 5462.399136095027, 5662.207290979246, 5700.173474846145, 5587.161925711135, 5624.128260119688, 5637.379888212294, 5695.74369153387, 5933.970330148343, 6430.3891130342545, 6432.195975503062, 6432.453241474457, 6460.289368169498, 6475.902861457082, 6147.723352449106, 6300.936990630081, 6369.172007639024, 6397.712102955355, 6406.583572067801, 5788.0, 5804.796832110856, 5817.825465236049, 5825.242718446617, 5830.7084645767545, 14622.780658290134, 14693.706125877423, 14706.044107133002, 14892.106473611619, 22630.886845565667, 2417.0452671194957, 2500.7374225706403, 2612.8040396970114, 2628.487444948236, 2794.720527947209, 10275.434851083188, 10432.895671043269, 10598.576056957712, 10733.926607339281, 11129.332240065622, 2075.2529089527757, 2080.937571872846, 2117.0579092303933, 2123.734533183352, 2142.5500644864596, 72475.27524724738, 79562.0, 80021.59989200076, 83156.0, 83519.58240208821, 11275.718107047389, 11632.592859249886, 11927.1650984432, 11929.403529823474, 11951.880481195076, 16362.754558681629, 16615.252313645917, 16620.750688739678, 16640.167991600614, 16721.080340581386, 9144.908550914406, 9168.35821492489, 9288.767780805534, 9356.0, 9366.0, 9214.585343659552, 9247.26021918245, 9314.301427392973, 9335.0, 9336.299777516713, 5046.495350464924, 5054.342935418372, 5074.3657042869645, 5090.040334471255, 5173.741312934339, 2111.809937105668, 2117.6611742121186, 2138.6150492911424, 2145.2813307541837, 2169.8264138868853, 1068.3589846092439, 1113.204059097749, 1115.4367044642565, 1126.881677423875, 1146.074892120974, 19290.517737056405, 19674.70487942682, 19819.504512387306, 20079.89560574153, 20309.796902031085, 1086.4024786367465, 1090.5801266512292, 1110.4059328259343, 1122.9887701123048, 1173.1904985559936, 11028.724281893017, 11309.717257068474, 11461.656150315504, 11511.424428778695, 11523.76952460946, 495271.0, 500715.0, 510572.2356941032, 516007.0, 530743.0, 79103.60448197722, 86829.56585217033, 94428.52785736165, 96600.0, 98049.50975245221, 1122.4443900269418, 1160.9129315301386, 1163.7148898519918, 1185.8102703567388, 1194.3908606610692, 17080.743788843425, 17378.73931891048, 17538.0, 17557.561060973578, 17641.382551610634, 39611.60388396079, 39656.0, 40482.595174049064, 41055.58944410637, 41480.1703965913, 56722.0, 59599.40400595938, 59712.0, 60046.0, 60278.0, 8503.23470887623, 8910.910890891008, 8925.687600933936, 9116.27069834425, 9198.862017069883, 10557.9472102639, 13246.80129798073, 13287.800682989957, 13340.0, 13524.39140238672, 8793.636986267138, 11499.0, 11771.0, 11882.76234475323, 11983.700407489883, 11165.330080195214, 11444.771104578029, 11545.191836571359, 11761.294322340345, 11814.46834892449, 12253.69365765863, 12406.875931240573, 12477.126601138196, 12545.184563003375, 12587.622371328875, 9028.09719028092, 9062.365634405793, 9137.908620913971, 9308.348415610842, 9322.161005509537, 11594.94202528982, 11864.11019173548, 11870.82193767077, 11894.107941904565, 11966.64100076999], "value": 9588.282029154794, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1586.3020271080727, 1606.421688192251, 1860.1350372076981, 1940.320887689308, 1960.5784756277405, 7800.687972481094, 8384.287335576468, 8562.743117706463, 8742.475451472916, 8756.036835948049, 8613.827723445527, 8624.482531048141, 8688.56557172142, 8699.34754893384, 8701.129887011295, 2380.666706661069, 2405.0980882169188, 2406.542756876188, 2409.8554086754853, 2418.467937053845, 2387.5821731197057, 2398.1726304424947, 2437.354101163184, 2463.2117722328862, 2468.987655061731, 2433.8783060846977, 2437.841540299875, 2449.0938352809485, 2459.7786199242064, 2475.084218839032, 2403.110848985869, 2420.8305418620703, 2423.5274121546217, 2437.4881274932195, 2438.5976313908186, 2382.2376839411395, 2408.12103582193, 2416.6495858100475, 2431.051889762994, 2437.695288088989, 8959.238464730506, 8984.955075224581, 8985.595648195847, 8992.640294388217, 9021.278297736231, 5383.246345511631, 5501.779928802883, 5538.861528461781, 5568.1091025435735, 5573.498385145316, 3416.8120753358558, 3447.9482807757904, 3482.251315967054, 3488.494168345601, 3500.6674365935196, 1095.7315457712832, 1099.7910397024539, 1118.7482816366335, 1130.2314426190187, 1141.2809929744208, 12754.489820407172, 12770.680732981657, 12772.425240864184, 12778.936105319506, 12835.422405991752, 4734.311428923684, 4780.067886762117, 4791.520847915181, 4850.393700787402, 4876.341693871353, 10642.776080750658, 10744.946275268574, 10754.73113172177, 10754.946225268981, 10777.08394786452, 5506.944930550643, 5539.307586551681, 5547.223388725582, 5547.472990065938, 5550.306211723535, 4834.105690447279, 4986.626003049713, 4990.17662085759, 5030.496950304941, 5054.166062599704, 3834.4631751554803, 3835.6931445484292, 3858.6334298241622, 3920.0, 3929.390944403622, 1176.2589568571916, 1180.527788884453, 1180.5808937826948, 1182.5920057580122, 1185.3539820797687, 978.882534095913, 1049.071571659087, 1062.5962134388883, 1077.757504561483, 1087.7117563845586, 6051.273847138372, 6130.1724267224245, 6192.133101365813, 6226.0, 6256.0, 1055.6727414501377, 1080.9081228095542, 1082.832161015044, 1083.4745148602922, 1112.3381587955155, 4310.698251122454, 4384.934225986613, 4390.100029493946, 4430.136123455895, 4501.189785838515, 10401.0, 10828.891711082946, 10829.566817327219, 10841.512131954081, 10843.83734243987, 10644.680659580225, 11019.118470522339, 11022.0, 11022.338659680285, 11030.834537481944, 8051.436399452099, 8359.038710548242, 8389.370797190235, 8392.118827523143, 8444.282236009885, 6164.630122192682, 6217.502599791916, 6269.341719119427, 6336.663336663337, 6387.648679322496, 9534.904650953678, 9719.562619682272, 9794.216462682825, 9815.067568580973, 9831.410115393099, 9661.323707340418, 9734.172595329477, 9989.500524973577, 10052.246081543768, 10066.647667331901, 7638.579878106758, 7866.213378662088, 7884.408669349937, 8000.759977200693, 8181.345492360478, 8489.066202717573, 8627.482351058956, 8683.348748843735, 8720.476771393736, 8743.868841967249, 7617.390608751176, 7629.580373079534, 7666.731664391719, 7805.063392392949, 7896.407769417202, 7596.658150383136, 7618.923810761821, 7621.733239336597, 7718.652660630173, 7773.805654858674, 7029.156501219886, 7080.150381954199, 7105.75129870452, 7107.75122870697, 7141.285871412817, 5062.696238225718, 5077.644564880571, 5210.27056212123, 5229.0, 5246.737663116753, 5490.835274941758, 5495.505404513613, 5590.105583106683, 5651.491365777099, 5663.235463212413], "value": 4770.376117941315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.42936871636675, 359.7481762766064, 360.5403111033433, 367.0713095867456, 367.58830110276494, 6475.320091390407, 6476.805695829133, 6496.992966090252, 6591.4067733904, 6626.370494802992, 6517.152770139875, 6527.911068458084, 6557.868842623145, 6577.9013314800195, 6584.572002819813, 5010.574101201393, 5039.72281524516, 5045.394552653668, 5055.898882022376, 5062.468440813716, 5053.646244762868, 5055.797768089272, 5056.898862022757, 5058.190689489682, 5063.189889617662, 5009.44896061433, 5012.398512178525, 5017.122003649347, 5022.547970682638, 5027.622928280393, 4728.361671174382, 4868.245421959602, 4993.350864387643, 5009.524095210949, 5019.949800501985, 4234.640055595278, 4237.300845360512, 4241.681873859442, 4243.893902652459, 4274.807633656493, 6243.5338041473815, 6243.968780156116, 6251.218597675291, 6251.656158911258, 6275.184226050585, 5725.5419566435, 5737.311522617271, 5740.856478588069, 5742.626729262584, 5744.82765517031, 6879.793606191823, 6882.0, 6883.3116688331265, 6892.414144797698, 6892.620905850176, 4606.147862645421, 4756.833510827105, 4762.523747625244, 4768.427788665383, 4778.4265888093305, 7135.286471352875, 7141.500094993353, 7161.283871612849, 7197.136343638745, 7213.098362704662, 4798.592119669797, 4836.0, 4842.6368022398465, 4843.854684359475, 4850.0, 4910.21436570147, 4911.68074075184, 4912.729799861042, 4916.680415772963, 4929.2359684248995, 5698.6580805151825, 5700.401457846866, 5703.515201207861, 5707.315122185281, 5707.800226992035, 6751.898721519181, 6851.520393572502, 6901.240863505011, 6914.377706006484, 6921.169459664872, 1698.651776385838, 1715.6740219358283, 1728.3432295727544, 1728.7147620642704, 1731.7921849378156, 1116.263266244275, 1116.6873275482878, 1124.8031594470935, 1138.5218208352512, 1145.6734830573246, 6639.734410623618, 6669.7665581704405, 6674.699638516181, 6680.365365290289, 6680.966595166993, 7027.508074434741, 7053.153621565445, 7075.2570980047385, 7093.290670932865, 7109.715611375487, 6593.0, 6613.338666133444, 6633.767818126439, 6683.431908287753, 6730.798076057726, 3815.389537673959, 3842.7310088293552, 3846.1346197105427, 3847.634474724897, 3853.0560012797046, 7249.927500724925, 7322.450816188807, 7424.071991001125, 7441.0, 7452.06849143857, 7243.963780181065, 7266.96366518164, 7320.0, 7325.120985481776, 7345.632718364061, 7528.5859277739155, 7549.358304544176, 7553.395728341719, 7558.433117516208, 7566.394688424913, 6956.826079348057, 7142.9285707144345, 7175.354218120394, 7199.49603527769, 7205.855882882208, 7080.114985626797, 7105.147382314155, 7116.679749411393, 7118.608476533634, 7131.572105673677, 7513.060867391576, 7515.962420187864, 7520.623968801428, 7523.796192609157, 7560.243975602396, 4990.900181996267, 5035.320231768665, 5048.0, 5050.393952725696, 5062.164742817394, 7008.299170082951, 7093.219745828058, 7099.680514376969, 7111.893321600283, 7124.323189297009, 6881.862362752817, 6888.242293347627, 6900.7929762107215, 6917.0, 6919.930800691928, 6847.863042739018, 6898.517103802887, 6902.620355880274, 6904.896426553706, 6921.93078069233, 6784.185897692111, 6784.864302714017, 6789.864202716017, 6795.93204067953, 6796.72813087471, 6827.146606674166, 6959.1996920352785, 6962.930370696228, 6967.7212911482975, 6982.615956122462, 6772.830679233059, 6781.084553585203, 6783.084283621648, 6796.2524122347495, 6801.251862295243], "value": 5210.210304199683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.9877001844973, 881.6737807011407, 920.4201353147516, 925.7963248085423, 947.2706016367401, 13657.931710341447, 13746.931265343672, 13811.447542098304, 13888.722225555483, 13925.442982280696, 12249.510019599227, 14165.78751318731, 14203.005789594732, 14224.502142425017, 14261.429542818274, 899.7480705402481, 924.3806649544821, 926.6293482606944, 926.7497775600584, 929.2101713543501, 917.6512925088457, 918.1782304837167, 924.2652091587204, 925.9907400926005, 928.6610387208688, 963.7012526116927, 964.0263334032608, 964.0985678390692, 967.2455484721928, 968.1722127580888, 958.5830163878666, 958.9808203835919, 960.0351646595162, 960.3901522533207, 965.4159233663673, 715.9498835081546, 716.373173473211, 717.0141056047934, 717.2827172827173, 719.2052781676225, 13897.235652039133, 13910.652233694138, 13959.860401395958, 13975.440982360693, 13987.860121398757, 14996.95021348506, 15155.848441515664, 15265.160416177107, 15275.465358712503, 15404.614884627861, 11963.0, 12059.155859089868, 12140.514379424812, 12177.817332740016, 12227.572034978733, 4056.5740597237454, 4070.450489183952, 4121.814518346651, 4134.37984302354, 4151.190517849021, 52107.0, 52614.47385526172, 52762.0, 53010.73494632541, 53117.0, 11580.131490138274, 11625.59310424131, 11715.882841171479, 11993.340366279765, 12166.756664866652, 21339.67990480144, 21411.67882481764, 21465.03407346673, 21708.131674732835, 21804.89097554534, 8100.108988011314, 8505.277051450696, 8507.829843403097, 8544.017437994586, 8628.0, 4828.179209534387, 4846.539578740014, 4884.267359895973, 4897.706137631753, 4984.501549844987, 3643.0, 3680.9079773005888, 3698.593154752975, 3726.552813662324, 3732.7760334380023, 648.1379764912655, 652.7323797243107, 662.4137638190236, 666.0508774995612, 668.0547025958215, 830.9044459887069, 835.8035861572525, 840.9663613455516, 860.8665656823202, 862.5902696219246, 9784.55969481375, 9980.900190998142, 10057.245706572035, 10122.898771012196, 10204.795904081877, 781.5349866829227, 815.6248125862052, 818.2431251092715, 831.3681601982537, 842.6839935024366, 4682.367880336179, 4694.765261736899, 4699.812007519729, 4895.632827537949, 4905.411350637875, 61673.07490387648, 62973.370266297665, 63166.0, 63270.36729632645, 64068.0, 53097.469025309256, 53543.73228133834, 54167.45832541624, 54351.45648543543, 55592.16611750827, 49699.0, 50506.74746626243, 50542.0, 50553.24170137451, 50842.74578627083, 9049.230815380766, 9213.953930230575, 9250.629974800933, 9383.436993780395, 9454.95272523633, 37477.0, 37576.81211593925, 38194.236115278094, 38331.616683832806, 38476.73200658454, 45884.31173532333, 46466.53533464622, 47491.0, 48795.75602122109, 48841.0, 11956.461959211681, 12004.639860804189, 12119.81820272679, 12152.878471215176, 12191.0, 12623.242605443702, 12729.490820367451, 12758.106932514635, 12896.48414063427, 13100.737985240052, 11339.829902551302, 11846.170768046153, 12057.095717821023, 12230.32733199647, 12321.322327272086, 12209.267443953391, 12213.084018698455, 12272.509099635914, 12451.564195253122, 12643.367831608199, 11615.303081815118, 12145.271283723005, 12175.0, 12197.268163910194, 12400.069994750249, 10008.699739007841, 10087.243456740627, 10252.794944101226, 10297.0, 10606.840897386699, 10218.846717299395, 10465.790684186426, 10480.58431399895, 10537.89462105369, 10576.41829699374], "value": 7078.644403849822, "unit": "score"}], "name": "dromaeo_css", "value": 7610.489080512214, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 101.0, 110.0, 112.0, 111.0, 101.0, 109.0, 110.0, 112.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 127.0, 161.0, 183.0, 136.0, 155.0, 160.0, 128.0, 191.0, 134.0], "value": 153.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 164.0, 162.0, 164.0, 164.0, 162.0, 162.0, 164.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 79.0, 178.0, 76.0, 73.0, 74.0, 77.0, 190.0, 76.0], "value": 97.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 145.0, 93.0, 93.0, 91.0, 147.0, 92.0, 92.0, 155.0], "value": 109.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 148.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 106.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 61.0, 59.0, 64.0, 66.0, 75.0, 57.0, 54.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 59.0, 59.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 86.0, 74.0, 83.0, 74.0, 74.0, 73.0, 75.0, 75.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 126.0, 126.0, 120.0, 121.0, 112.0, 122.0, 116.0, 134.0, 132.0], "value": 122.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 169.0, 166.0, 165.0, 164.0, 166.0, 165.0, 168.0, 169.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 66.0, 66.0, 68.0, 67.0, 75.0, 67.0, 66.0, 75.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1544.6000000000001, "alertThreshold": 2.0}]} 05:07:25 INFO - Return code: 0 05:07:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:07:26 ERROR - # TBPL SUCCESS # 05:07:26 INFO - Running post-action listener: _resource_record_post_action 05:07:26 INFO - Running post-run listener: _resource_record_post_run 05:07:27 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 28672; Write bytes: 464351232; Read time: 28; Write time: 303692 05:07:27 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 242057216; Read time: 0; Write time: 207152 05:07:27 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 4096; Write bytes: 221696000; Read time: 20; Write time: 96516 05:07:27 INFO - Running post-run listener: _upload_blobber_files 05:07:27 INFO - Blob upload gear active. 05:07:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:27 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 05:07:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:07:27 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:07:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:07:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:28 INFO - (blobuploader) - INFO - Done attempting. 05:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:07:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:29 INFO - (blobuploader) - INFO - Done attempting. 05:07:29 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:29 INFO - Return code: 0 05:07:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3ca614b9f11499c625246d7e0bdf8290701a9c08a3d0fa52b19330a08e44b7c3d181797654b46eb1fb022ac03d8ae0660ac080ecdacb05720e699953606008a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6e099644658984ae8361cc2cba9527e3ab64c3e960e9f4a6c2a1ab0ec6bfbbdbbc0c0e3759f2a148ed57c5bcf9a3a5786e110c579dfd297759efe2ac598aeb77"} 05:07:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:29 INFO - Contents: 05:07:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3ca614b9f11499c625246d7e0bdf8290701a9c08a3d0fa52b19330a08e44b7c3d181797654b46eb1fb022ac03d8ae0660ac080ecdacb05720e699953606008a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6e099644658984ae8361cc2cba9527e3ab64c3e960e9f4a6c2a1ab0ec6bfbbdbbc0c0e3759f2a148ed57c5bcf9a3a5786e110c579dfd297759efe2ac598aeb77"} 05:07:29 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:29 INFO - Copying logs to upload dir... 05:07:29 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1227.252828 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-07-25 05:07:28.720168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 05:07:28.723719) ========= 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=6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3ca614b9f11499c625246d7e0bdf8290701a9c08a3d0fa52b19330a08e44b7c3d181797654b46eb1fb022ac03d8ae0660ac080ecdacb05720e699953606008a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6e099644658984ae8361cc2cba9527e3ab64c3e960e9f4a6c2a1ab0ec6bfbbdbbc0c0e3759f2a148ed57c5bcf9a3a5786e110c579dfd297759efe2ac598aeb77"} build_url:https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011203 build_url: 'https://queue.taskcluster.net/v1/task/FkBW4SKSTcqk7L0b4YT3Vg/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3ca614b9f11499c625246d7e0bdf8290701a9c08a3d0fa52b19330a08e44b7c3d181797654b46eb1fb022ac03d8ae0660ac080ecdacb05720e699953606008a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6e099644658984ae8361cc2cba9527e3ab64c3e960e9f4a6c2a1ab0ec6bfbbdbbc0c0e3759f2a148ed57c5bcf9a3a5786e110c579dfd297759efe2ac598aeb77"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 05:07:28.748448) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 05:07:28.748741) ========= 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=6d15e502d8e29d5d2676d664000001c3-1469447150.793318-879599744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004837 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 05:07:28.831991) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 05:07:28.832289) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 05:07:28.832569) ========= ========= Total master_lag: 0.16 =========