builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-013 starttime: 1461127154.49 results: success (0) buildid: 20160419205830 builduid: 84baf73af66c4090a0f801b68eaaf0ea revision: d4cd977c44f0eaa7d7a6ec2f77481950e33833c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.493671) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.494146) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.494421) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.515801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.516216) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-19 21:39:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 95.9M=0s 2016-04-19 21:39:15 (95.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091247 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.622271) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.622572) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013919 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.674245) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:14.674556) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d4cd977c44f0eaa7d7a6ec2f77481950e33833c6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d4cd977c44f0eaa7d7a6ec2f77481950e33833c6 --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=9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-19 21:39:15,208 truncating revision to first 12 chars 2016-04-19 21:39:15,208 Setting DEBUG logging. 2016-04-19 21:39:15,208 attempt 1/10 2016-04-19 21:39:15,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d4cd977c44f0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 21:39:15,498 unpacking tar archive at: fx-team-d4cd977c44f0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.613883 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:15.300947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:15.301275) ========= script_repo_revision: d4cd977c44f0eaa7d7a6ec2f77481950e33833c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:15.301644) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:15.301909) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 21:39:15.311508) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-19 21:39:15.311812) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:39:15 INFO - MultiFileLogger online at 20160419 21:39:15 in /builds/slave/test 21:39:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 21:39:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:39:15 INFO - {'append_to_log': False, 21:39:15 INFO - 'base_work_dir': '/builds/slave/test', 21:39:15 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:39:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:39:15 INFO - 'branch': 'Fx-Team-Non-PGO', 21:39:15 INFO - 'buildbot_json_path': 'buildprops.json', 21:39:15 INFO - 'config_files': ('talos/linux_config.py',), 21:39:15 INFO - 'default_actions': ('clobber', 21:39:15 INFO - 'read-buildbot-config', 21:39:15 INFO - 'download-and-extract', 21:39:15 INFO - 'populate-webroot', 21:39:15 INFO - 'create-virtualenv', 21:39:15 INFO - 'install', 21:39:15 INFO - 'run-tests'), 21:39:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:39:15 INFO - 'download_minidump_stackwalk': True, 21:39:15 INFO - 'download_symbols': 'ondemand', 21:39:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:39:15 INFO - 'tooltool.py': '/tools/tooltool.py', 21:39:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:39:15 INFO - '/tools/misc-python/virtualenv.py')}, 21:39:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:39:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:39:15 INFO - 'installer_path': 'installer.exe', 21:39:15 INFO - 'log_level': 'info', 21:39:15 INFO - 'log_name': 'talos', 21:39:15 INFO - 'log_to_console': True, 21:39:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:39:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:39:15 INFO - 'opt_config_files': (), 21:39:15 INFO - 'pip_index': False, 21:39:15 INFO - 'sps_profile': False, 21:39:15 INFO - 'sps_profile_interval': 0, 21:39:15 INFO - 'suite': 'dromaeojs-e10s', 21:39:15 INFO - 'system_bits': '32', 21:39:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:39:15 INFO - 'title': 'talos-linux64-ix-013', 21:39:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:39:15 INFO - 'use_talos_json': True, 21:39:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:39:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:39:15 INFO - 'work_dir': 'build'} 21:39:15 INFO - ##### 21:39:15 INFO - ##### Running clobber step. 21:39:15 INFO - ##### 21:39:15 INFO - Running pre-action listener: _resource_record_pre_action 21:39:15 INFO - Running main action method: clobber 21:39:15 INFO - rmtree: /builds/slave/test/build 21:39:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:39:16 INFO - Running post-action listener: _resource_record_post_action 21:39:16 INFO - ##### 21:39:16 INFO - ##### Running read-buildbot-config step. 21:39:16 INFO - ##### 21:39:16 INFO - Running pre-action listener: _resource_record_pre_action 21:39:16 INFO - Running main action method: read_buildbot_config 21:39:16 INFO - Using buildbot properties: 21:39:16 INFO - { 21:39:16 INFO - "project": "", 21:39:16 INFO - "product": "firefox", 21:39:16 INFO - "script_repo_revision": "production", 21:39:16 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 21:39:16 INFO - "repository": "", 21:39:16 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 21:39:16 INFO - "buildid": "20160419205830", 21:39:16 INFO - "pgo_build": "False", 21:39:16 INFO - "basedir": "/builds/slave/test", 21:39:16 INFO - "buildnumber": 1176, 21:39:16 INFO - "slavename": "talos-linux64-ix-013", 21:39:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:39:16 INFO - "platform": "ubuntu64_hw", 21:39:16 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:39:16 INFO - "branch": "fx-team", 21:39:16 INFO - "repo_path": "integration/fx-team", 21:39:16 INFO - "revision": "d4cd977c44f0eaa7d7a6ec2f77481950e33833c6", 21:39:16 INFO - "stage_platform": "linux64", 21:39:16 INFO - "builduid": "84baf73af66c4090a0f801b68eaaf0ea", 21:39:16 INFO - "slavebuilddir": "test" 21:39:16 INFO - } 21:39:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 21:39:16 INFO - Running post-action listener: _resource_record_post_action 21:39:16 INFO - ##### 21:39:16 INFO - ##### Running download-and-extract step. 21:39:16 INFO - ##### 21:39:16 INFO - Running pre-action listener: _resource_record_pre_action 21:39:16 INFO - Running main action method: download_and_extract 21:39:16 INFO - mkdir: /builds/slave/test/build/tests 21:39:16 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')]} 21:39:16 INFO - https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:39:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 21:39:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 21:39:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 21:39:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:39:19 INFO - Downloaded 1361 bytes. 21:39:19 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 21:39:19 INFO - Using the following test package requirements: 21:39:19 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 21:39:19 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:39:19 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:39:19 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'jsshell-linux-x86_64.zip'], 21:39:19 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:39:19 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 21:39:19 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:39:19 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:39:19 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:39:19 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:19 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:39:19 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:19 INFO - https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:39:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 21:39:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 21:39:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 21:39:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:39:20 INFO - Downloaded 22267191 bytes. 21:39:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:39:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:39:21 INFO - Return code: 0 21:39:21 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:21 INFO - https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:39:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 21:39:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 21:39:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 21:39:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:39:27 INFO - Downloaded 22267191 bytes. 21:39:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:39:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:39:28 INFO - Return code: 0 21:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:28 INFO - https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:39:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 21:39:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 21:39:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 21:39:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:39:29 INFO - Downloaded 10845952 bytes. 21:39:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:39:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:39:29 INFO - Return code: 0 21:39: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')]} 21:39:29 INFO - https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:39:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 21:39:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 21:39:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 21:39:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:39:30 INFO - Downloaded 54504819 bytes. 21:39:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 21:39:30 INFO - mkdir: /builds/slave/test/properties 21:39:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:39:30 INFO - Writing to file /builds/slave/test/properties/build_url 21:39:30 INFO - Contents: 21:39:30 INFO - build_url:https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 21:39:31 INFO - Running post-action listener: _resource_record_post_action 21:39:31 INFO - Running post-action listener: set_extra_try_arguments 21:39:31 INFO - ##### 21:39:31 INFO - ##### Running populate-webroot step. 21:39:31 INFO - ##### 21:39:31 INFO - Running pre-action listener: _resource_record_pre_action 21:39:31 INFO - Running main action method: populate_webroot 21:39:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:39:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:39:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:39:31 INFO - '--tppagecycles', 21:39:31 INFO - '7'], 21:39:31 INFO - 'tests': ['tsvgm']}}, 21:39:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:39:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:39:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:39:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:39:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:39:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:39:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['damp', 'tps']}, 21:39:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'tests': ['damp', 'tps']}, 21:39:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['dromaeo_dom']}, 21:39:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:39:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['a11yr', 21:39:31 INFO - 'ts_paint', 21:39:31 INFO - 'tpaint', 21:39:31 INFO - 'sessionrestore', 21:39:31 INFO - 'sessionrestore_no_auto_restore', 21:39:31 INFO - 'tabpaint']}, 21:39:31 INFO - 'other-e10s': {'tests': ['a11yr', 21:39:31 INFO - 'ts_paint', 21:39:31 INFO - 'tpaint', 21:39:31 INFO - 'sessionrestore', 21:39:31 INFO - 'sessionrestore_no_auto_restore', 21:39:31 INFO - 'tabpaint']}, 21:39:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:39:31 INFO - 'ts_paint', 21:39:31 INFO - 'tpaint', 21:39:31 INFO - 'sessionrestore', 21:39:31 INFO - 'sessionrestore_no_auto_restore', 21:39:31 INFO - 'tabpaint']}, 21:39:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:39:31 INFO - 'ts_paint', 21:39:31 INFO - 'tpaint', 21:39:31 INFO - 'sessionrestore', 21:39:31 INFO - 'sessionrestore_no_auto_restore', 21:39:31 INFO - 'tabpaint']}, 21:39:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['a11yr', 21:39:31 INFO - 'ts_paint', 21:39:31 INFO - 'tpaint', 21:39:31 INFO - 'sessionrestore', 21:39:31 INFO - 'sessionrestore_no_auto_restore', 21:39:31 INFO - 'tabpaint']}, 21:39:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['a11yr', 21:39:31 INFO - 'ts_paint', 21:39:31 INFO - 'tpaint', 21:39:31 INFO - 'sessionrestore', 21:39:31 INFO - 'sessionrestore_no_auto_restore', 21:39:31 INFO - 'tabpaint']}, 21:39:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['tsvgx', 21:39:31 INFO - 'tsvgr_opacity', 21:39:31 INFO - 'tart', 21:39:31 INFO - 'tscrollx', 21:39:31 INFO - 'cart']}, 21:39:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:39:31 INFO - 'tsvgr_opacity', 21:39:31 INFO - 'tart', 21:39:31 INFO - 'tscrollx', 21:39:31 INFO - 'cart']}, 21:39:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'talos_options': ['--disable-e10s'], 21:39:31 INFO - 'tests': ['tp5o']}, 21:39:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'tests': ['tp5o']}, 21:39:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'talos_options': ['--disable-e10s', 21:39:31 INFO - '--xperf_path', 21:39:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:39:31 INFO - 'tests': ['tp5n']}, 21:39:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:31 INFO - 'talos_options': ['--xperf_path', 21:39:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:39:31 INFO - 'tests': ['tp5n']}}, 21:39:31 INFO - 'talos.zip': {'path': '', 21:39:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:39:31 INFO - Running post-action listener: _resource_record_post_action 21:39:31 INFO - ##### 21:39:31 INFO - ##### Running create-virtualenv step. 21:39:31 INFO - ##### 21:39:31 INFO - Running pre-action listener: _resource_record_pre_action 21:39:31 INFO - Running main action method: create_virtualenv 21:39:31 INFO - Creating virtualenv /builds/slave/test/build/venv 21:39:31 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 21:39:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:39:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:39:31 INFO - Using real prefix '/usr' 21:39:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:39:32 INFO - Installing distribute.............................................................................................................................................................................................done. 21:39:34 INFO - Installing pip.................done. 21:39:34 INFO - Return code: 0 21:39:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:34 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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:34 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 21:39:34 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 21:39:34 INFO - Using env: {'DISPLAY': ':0', 21:39:34 INFO - 'HOME': '/home/cltbld', 21:39:34 INFO - 'LANG': 'en_US.UTF-8', 21:39:34 INFO - 'LANGUAGE': 'en_US:en', 21:39:34 INFO - 'LOGNAME': 'cltbld', 21:39:34 INFO - 'MAIL': '/var/mail/cltbld', 21:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:34 INFO - 'MOZ_NO_REMOTE': '1', 21:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:34 INFO - 'NO_EM_RESTART': '1', 21:39:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:34 INFO - 'PWD': '/builds/slave/test', 21:39:34 INFO - 'SHELL': '/bin/bash', 21:39:34 INFO - 'SHLVL': '1', 21:39:34 INFO - 'TERM': 'linux', 21:39:34 INFO - 'TMOUT': '86400', 21:39:34 INFO - 'USER': 'cltbld', 21:39:34 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:34 INFO - '_': '/tools/buildbot/bin/python'} 21:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:34 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 21:39:34 INFO - Cleaning up... 21:39:34 INFO - Return code: 0 21:39:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:34 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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:34 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 21:39:34 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 21:39:34 INFO - Using env: {'DISPLAY': ':0', 21:39:34 INFO - 'HOME': '/home/cltbld', 21:39:34 INFO - 'LANG': 'en_US.UTF-8', 21:39:34 INFO - 'LANGUAGE': 'en_US:en', 21:39:34 INFO - 'LOGNAME': 'cltbld', 21:39:34 INFO - 'MAIL': '/var/mail/cltbld', 21:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:34 INFO - 'MOZ_NO_REMOTE': '1', 21:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:34 INFO - 'NO_EM_RESTART': '1', 21:39:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:34 INFO - 'PWD': '/builds/slave/test', 21:39:34 INFO - 'SHELL': '/bin/bash', 21:39:34 INFO - 'SHLVL': '1', 21:39:34 INFO - 'TERM': 'linux', 21:39:34 INFO - 'TMOUT': '86400', 21:39:34 INFO - 'USER': 'cltbld', 21:39:34 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:34 INFO - '_': '/tools/buildbot/bin/python'} 21:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:34 INFO - Downloading/unpacking psutil>=0.7.1 21:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:39:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:39:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:39:36 INFO - Installing collected packages: psutil 21:39:36 INFO - Running setup.py install for psutil 21:39:36 INFO - building 'psutil._psutil_linux' extension 21:39:36 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 21:39:36 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 21:39:37 INFO - building 'psutil._psutil_posix' extension 21:39:37 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 21:39:37 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 21:39:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:39:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:39:37 INFO - Successfully installed psutil 21:39:37 INFO - Cleaning up... 21:39:37 INFO - Return code: 0 21:39:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 21:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:39:37 INFO - Using env: {'DISPLAY': ':0', 21:39:37 INFO - 'HOME': '/home/cltbld', 21:39:37 INFO - 'LANG': 'en_US.UTF-8', 21:39:37 INFO - 'LANGUAGE': 'en_US:en', 21:39:37 INFO - 'LOGNAME': 'cltbld', 21:39:37 INFO - 'MAIL': '/var/mail/cltbld', 21:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:37 INFO - 'MOZ_NO_REMOTE': '1', 21:39:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:37 INFO - 'NO_EM_RESTART': '1', 21:39:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:37 INFO - 'PWD': '/builds/slave/test', 21:39:37 INFO - 'SHELL': '/bin/bash', 21:39:37 INFO - 'SHLVL': '1', 21:39:37 INFO - 'TERM': 'linux', 21:39:37 INFO - 'TMOUT': '86400', 21:39:37 INFO - 'USER': 'cltbld', 21:39:37 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:37 INFO - '_': '/tools/buildbot/bin/python'} 21:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:39:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:39:39 INFO - Installing collected packages: mozsystemmonitor 21:39:39 INFO - Running setup.py install for mozsystemmonitor 21:39:39 INFO - Successfully installed mozsystemmonitor 21:39:39 INFO - Cleaning up... 21:39:39 INFO - Return code: 0 21:39:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:39: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')]} 21:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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')]} 21:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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', '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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:39: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 blobuploader==1.2.4 21:39:39 INFO - Using env: {'DISPLAY': ':0', 21:39:39 INFO - 'HOME': '/home/cltbld', 21:39:39 INFO - 'LANG': 'en_US.UTF-8', 21:39:39 INFO - 'LANGUAGE': 'en_US:en', 21:39:39 INFO - 'LOGNAME': 'cltbld', 21:39:39 INFO - 'MAIL': '/var/mail/cltbld', 21:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:39 INFO - 'MOZ_NO_REMOTE': '1', 21:39:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:39 INFO - 'NO_EM_RESTART': '1', 21:39:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:39 INFO - 'PWD': '/builds/slave/test', 21:39:39 INFO - 'SHELL': '/bin/bash', 21:39:39 INFO - 'SHLVL': '1', 21:39:39 INFO - 'TERM': 'linux', 21:39:39 INFO - 'TMOUT': '86400', 21:39:39 INFO - 'USER': 'cltbld', 21:39:39 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:39 INFO - '_': '/tools/buildbot/bin/python'} 21:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:39 INFO - Downloading/unpacking blobuploader==1.2.4 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39:41 INFO - Downloading blobuploader-1.2.4.tar.gz 21:39:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:39:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:39:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:42 INFO - Downloading docopt-0.6.1.tar.gz 21:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:39:42 INFO - Installing collected packages: blobuploader, requests, docopt 21:39:42 INFO - Running setup.py install for blobuploader 21:39:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:39:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:39:42 INFO - Running setup.py install for requests 21:39:42 INFO - Running setup.py install for docopt 21:39:43 INFO - Successfully installed blobuploader requests docopt 21:39:43 INFO - Cleaning up... 21:39:43 INFO - Return code: 0 21:39:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:43 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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:43 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 21:39:43 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 21:39:43 INFO - Using env: {'DISPLAY': ':0', 21:39:43 INFO - 'HOME': '/home/cltbld', 21:39:43 INFO - 'LANG': 'en_US.UTF-8', 21:39:43 INFO - 'LANGUAGE': 'en_US:en', 21:39:43 INFO - 'LOGNAME': 'cltbld', 21:39:43 INFO - 'MAIL': '/var/mail/cltbld', 21:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:43 INFO - 'MOZ_NO_REMOTE': '1', 21:39:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:43 INFO - 'NO_EM_RESTART': '1', 21:39:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:43 INFO - 'PWD': '/builds/slave/test', 21:39:43 INFO - 'SHELL': '/bin/bash', 21:39:43 INFO - 'SHLVL': '1', 21:39:43 INFO - 'TERM': 'linux', 21:39:43 INFO - 'TMOUT': '86400', 21:39:43 INFO - 'USER': 'cltbld', 21:39:43 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:43 INFO - '_': '/tools/buildbot/bin/python'} 21:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:39:43 INFO - Running setup.py (path:/tmp/pip-p28clJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:39:43 INFO - Running setup.py (path:/tmp/pip-cViumr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:39:43 INFO - Running setup.py (path:/tmp/pip-Pd9Rqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:39:43 INFO - Running setup.py (path:/tmp/pip-bWNh94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:39:43 INFO - Running setup.py (path:/tmp/pip-7VrSDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:39:43 INFO - Running setup.py (path:/tmp/pip-KXfSqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:39:43 INFO - Running setup.py (path:/tmp/pip-z2Ep5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:39:43 INFO - Running setup.py (path:/tmp/pip-FwIZZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:39:43 INFO - Running setup.py (path:/tmp/pip-wv8AxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:39:43 INFO - Running setup.py (path:/tmp/pip-kgrThR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:39:44 INFO - Running setup.py (path:/tmp/pip-ljdKre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:39:44 INFO - Running setup.py (path:/tmp/pip-oX5C_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:39:44 INFO - Running setup.py (path:/tmp/pip-PKl0Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:39:44 INFO - Running setup.py (path:/tmp/pip-91XOs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:44 INFO - Running setup.py (path:/tmp/pip-fRUJvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:39:44 INFO - Running setup.py (path:/tmp/pip-dFMBDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:39:44 INFO - Running setup.py (path:/tmp/pip-wjcJSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:39:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:39:44 INFO - Running setup.py install for manifestparser 21:39:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:39:44 INFO - Running setup.py install for mozcrash 21:39:44 INFO - Running setup.py install for mozdebug 21:39:44 INFO - Running setup.py install for mozdevice 21:39:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:39:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Running setup.py install for mozfile 21:39:45 INFO - Running setup.py install for mozhttpd 21:39:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Running setup.py install for mozinfo 21:39:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Running setup.py install for mozInstall 21:39:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Running setup.py install for mozleak 21:39:45 INFO - Running setup.py install for mozlog 21:39:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Running setup.py install for moznetwork 21:39:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Running setup.py install for mozprocess 21:39:45 INFO - Running setup.py install for mozprofile 21:39:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:39:45 INFO - Running setup.py install for mozrunner 21:39:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:39:46 INFO - Running setup.py install for mozscreenshot 21:39:46 INFO - Running setup.py install for moztest 21:39:46 INFO - Running setup.py install for mozversion 21:39:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:39:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:39:46 INFO - Cleaning up... 21:39:46 INFO - Return code: 0 21:39:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:46 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')]} 21:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:46 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')]} 21:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:46 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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:46 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 21:39:46 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 21:39:46 INFO - Using env: {'DISPLAY': ':0', 21:39:46 INFO - 'HOME': '/home/cltbld', 21:39:46 INFO - 'LANG': 'en_US.UTF-8', 21:39:46 INFO - 'LANGUAGE': 'en_US:en', 21:39:46 INFO - 'LOGNAME': 'cltbld', 21:39:46 INFO - 'MAIL': '/var/mail/cltbld', 21:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:46 INFO - 'MOZ_NO_REMOTE': '1', 21:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:46 INFO - 'NO_EM_RESTART': '1', 21:39:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:46 INFO - 'PWD': '/builds/slave/test', 21:39:46 INFO - 'SHELL': '/bin/bash', 21:39:46 INFO - 'SHLVL': '1', 21:39:46 INFO - 'TERM': 'linux', 21:39:46 INFO - 'TMOUT': '86400', 21:39:46 INFO - 'USER': 'cltbld', 21:39:46 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:46 INFO - '_': '/tools/buildbot/bin/python'} 21:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:39:46 INFO - Running setup.py (path:/tmp/pip-HBC6tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:39:46 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)) 21:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:39:46 INFO - Running setup.py (path:/tmp/pip-Pfmykg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:39:46 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)) 21:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:39:46 INFO - Running setup.py (path:/tmp/pip-8y1km7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:39:46 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)) 21:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:39:46 INFO - Running setup.py (path:/tmp/pip-Wpngag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:39:46 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)) 21:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:39:46 INFO - Running setup.py (path:/tmp/pip-S8F5Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:39:46 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)) 21:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:39:46 INFO - Running setup.py (path:/tmp/pip-Ga9p7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:39:46 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)) 21:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:39:46 INFO - Running setup.py (path:/tmp/pip-2pDXAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:39:47 INFO - Running setup.py (path:/tmp/pip-j7t6Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:39:47 INFO - Running setup.py (path:/tmp/pip-o9MU4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:39:47 INFO - Running setup.py (path:/tmp/pip-GD4iti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:39:47 INFO - Running setup.py (path:/tmp/pip-YTBBSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:39:47 INFO - Running setup.py (path:/tmp/pip-0tsab6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:39:47 INFO - Running setup.py (path:/tmp/pip-gVLwzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:39:47 INFO - Running setup.py (path:/tmp/pip-O58471-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:47 INFO - Running setup.py (path:/tmp/pip-6h4wK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:39:47 INFO - Running setup.py (path:/tmp/pip-Dhesl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:39:47 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)) 21:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:39:47 INFO - Running setup.py (path:/tmp/pip-Vyxw3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:39:47 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)) 21:39:47 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)) 21:39:47 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)) 21:39:47 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)) 21:39:47 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)) 21:39:47 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)) 21:39:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:39:47 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 21:39:47 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 21:39:47 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 21:39:47 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 21:39:47 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 21:39:47 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 21:39:49 INFO - Downloading blessings-1.6.tar.gz 21:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:39:49 INFO - Installing collected packages: blessings 21:39:49 INFO - Running setup.py install for blessings 21:39:49 INFO - Successfully installed blessings 21:39:49 INFO - Cleaning up... 21:39:49 INFO - Return code: 0 21:39:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:39:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:39:50 INFO - Reading from file tmpfile_stdout 21:39:50 INFO - Current package versions: 21:39:50 INFO - argparse == 1.2.1 21:39:50 INFO - blessings == 1.6 21:39:50 INFO - blobuploader == 1.2.4 21:39:50 INFO - docopt == 0.6.1 21:39:50 INFO - manifestparser == 1.1 21:39:50 INFO - mozInstall == 1.12 21:39:50 INFO - mozcrash == 0.17 21:39:50 INFO - mozdebug == 0.1 21:39:50 INFO - mozdevice == 0.48 21:39:50 INFO - mozfile == 1.2 21:39:50 INFO - mozhttpd == 0.7 21:39:50 INFO - mozinfo == 0.9 21:39:50 INFO - mozleak == 0.1 21:39:50 INFO - mozlog == 3.1 21:39:50 INFO - moznetwork == 0.27 21:39:50 INFO - mozprocess == 0.22 21:39:50 INFO - mozprofile == 0.28 21:39:50 INFO - mozrunner == 6.11 21:39:50 INFO - mozscreenshot == 0.1 21:39:50 INFO - mozsystemmonitor == 0.0 21:39:50 INFO - moztest == 0.7 21:39:50 INFO - mozversion == 1.4 21:39:50 INFO - psutil == 3.1.1 21:39:50 INFO - requests == 1.2.3 21:39:50 INFO - wsgiref == 0.1.2 21:39:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:50 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')]} 21:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:50 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')]} 21:39:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:50 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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:50 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 21:39:50 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 21:39:50 INFO - Using env: {'DISPLAY': ':0', 21:39:50 INFO - 'HOME': '/home/cltbld', 21:39:50 INFO - 'LANG': 'en_US.UTF-8', 21:39:50 INFO - 'LANGUAGE': 'en_US:en', 21:39:50 INFO - 'LOGNAME': 'cltbld', 21:39:50 INFO - 'MAIL': '/var/mail/cltbld', 21:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:50 INFO - 'MOZ_NO_REMOTE': '1', 21:39:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:50 INFO - 'NO_EM_RESTART': '1', 21:39:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:50 INFO - 'PWD': '/builds/slave/test', 21:39:50 INFO - 'SHELL': '/bin/bash', 21:39:50 INFO - 'SHLVL': '1', 21:39:50 INFO - 'TERM': 'linux', 21:39:50 INFO - 'TMOUT': '86400', 21:39:50 INFO - 'USER': 'cltbld', 21:39:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:50 INFO - '_': '/tools/buildbot/bin/python'} 21:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 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)) 21:39:50 INFO - Cleaning up... 21:39:50 INFO - Return code: 0 21:39:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:39:50 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')]} 21:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:50 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')]} 21:39:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:50 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 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:50 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 21:39:50 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 21:39:50 INFO - Using env: {'DISPLAY': ':0', 21:39:50 INFO - 'HOME': '/home/cltbld', 21:39:50 INFO - 'LANG': 'en_US.UTF-8', 21:39:50 INFO - 'LANGUAGE': 'en_US:en', 21:39:50 INFO - 'LOGNAME': 'cltbld', 21:39:50 INFO - 'MAIL': '/var/mail/cltbld', 21:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:50 INFO - 'MOZ_NO_REMOTE': '1', 21:39:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:50 INFO - 'NO_EM_RESTART': '1', 21:39:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:50 INFO - 'PWD': '/builds/slave/test', 21:39:50 INFO - 'SHELL': '/bin/bash', 21:39:50 INFO - 'SHLVL': '1', 21:39:50 INFO - 'TERM': 'linux', 21:39:50 INFO - 'TMOUT': '86400', 21:39:50 INFO - 'USER': 'cltbld', 21:39:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:50 INFO - '_': '/tools/buildbot/bin/python'} 21:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:50 INFO - Downloading/unpacking jsonschema 21:39:50 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 21:39:50 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 21:39:50 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 21:39:50 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 21:39:50 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 21:39:50 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 21:39:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:39:52 INFO - Downloading/unpacking functools32 (from jsonschema) 21:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:52 INFO - Downloading functools32-3.2.3-2.tar.gz 21:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:39:52 INFO - warning: no files found matching '*.txt' 21:39:52 INFO - no previously-included directories found matching 'build' 21:39:52 INFO - no previously-included directories found matching 'dist' 21:39:52 INFO - no previously-included directories found matching '.git*' 21:39:52 INFO - Installing collected packages: jsonschema, functools32 21:39:52 INFO - Running setup.py install for functools32 21:39:52 INFO - warning: no files found matching '*.txt' 21:39:52 INFO - no previously-included directories found matching 'build' 21:39:52 INFO - no previously-included directories found matching 'dist' 21:39:52 INFO - no previously-included directories found matching '.git*' 21:39:52 INFO - Successfully installed jsonschema functools32 21:39:52 INFO - Cleaning up... 21:39:52 INFO - Return code: 0 21:39:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f49c5c34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b45a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', '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 21:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:39:52 INFO - Using env: {'DISPLAY': ':0', 21:39:52 INFO - 'HOME': '/home/cltbld', 21:39:52 INFO - 'LANG': 'en_US.UTF-8', 21:39:52 INFO - 'LANGUAGE': 'en_US:en', 21:39:52 INFO - 'LOGNAME': 'cltbld', 21:39:52 INFO - 'MAIL': '/var/mail/cltbld', 21:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:52 INFO - 'MOZ_NO_REMOTE': '1', 21:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:52 INFO - 'NO_EM_RESTART': '1', 21:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:52 INFO - 'PWD': '/builds/slave/test', 21:39:52 INFO - 'SHELL': '/bin/bash', 21:39:52 INFO - 'SHLVL': '1', 21:39:52 INFO - 'TERM': 'linux', 21:39:52 INFO - 'TMOUT': '86400', 21:39:52 INFO - 'USER': 'cltbld', 21:39:52 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:52 INFO - '_': '/tools/buildbot/bin/python'} 21:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:53 INFO - Downloading/unpacking flake8 21:39:53 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 21:39:53 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 21:39:53 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 21:39:53 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 21:39:53 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 21:39:53 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 21:39:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:39:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:39:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:39:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:39:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:39:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:39:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:39:55 INFO - no previously-included directories found matching 'docs/_build' 21:39:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:39:55 INFO - Running setup.py install for pep8 21:39:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:39:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:39:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:39:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:39:55 INFO - no previously-included directories found matching 'docs/_build' 21:39:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:39:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:39:55 INFO - Cleaning up... 21:39:55 INFO - Return code: 0 21:39:55 INFO - Running post-action listener: _resource_record_post_action 21:39:55 INFO - Running post-action listener: _start_resource_monitoring 21:39:55 INFO - Starting resource monitoring. 21:39:55 INFO - ##### 21:39:55 INFO - ##### Running install step. 21:39:55 INFO - ##### 21:39:55 INFO - Running pre-action listener: _resource_record_pre_action 21:39:55 INFO - Running main action method: install 21:39:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:39:56 INFO - Reading from file tmpfile_stdout 21:39:56 INFO - Detecting whether we're running mozinstall >=1.0... 21:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:39:56 INFO - Reading from file tmpfile_stdout 21:39:56 INFO - Output received: 21:39:56 INFO - Usage: mozinstall [options] installer 21:39:56 INFO - Options: 21:39:56 INFO - -h, --help show this help message and exit 21:39:56 INFO - -d DEST, --destination=DEST 21:39:56 INFO - Directory to install application into. [default: 21:39:56 INFO - "/builds/slave/test"] 21:39:56 INFO - --app=APP Application being installed. [default: firefox] 21:39:56 INFO - mkdir: /builds/slave/test/build/application 21:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:40:11 INFO - Reading from file tmpfile_stdout 21:40:11 INFO - Output received: 21:40:11 INFO - /builds/slave/test/build/application/firefox/firefox 21:40:11 INFO - Running post-action listener: _resource_record_post_action 21:40:11 INFO - ##### 21:40:11 INFO - ##### Running run-tests step. 21:40:11 INFO - ##### 21:40:11 INFO - Running pre-action listener: _resource_record_pre_action 21:40:11 INFO - Running main action method: run_tests 21:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:40:11 INFO - Python 2.7.3 21:40:11 INFO - Return code: 0 21:40:11 INFO - grabbing minidump binary from tooltool 21:40:11 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')]} 21:40:11 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 0x1743410>, '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 0x1744310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17447a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:40:11 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 21:40:11 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 21:40:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:40:12 INFO - Return code: 0 21:40:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:40:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:40:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:40:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:40:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:40:13 INFO - Return code: 0 21:40:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 21:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 21:40:13 INFO - Using env: {'DISPLAY': ':0', 21:40:13 INFO - 'HOME': '/home/cltbld', 21:40:13 INFO - 'LANG': 'en_US.UTF-8', 21:40:13 INFO - 'LANGUAGE': 'en_US:en', 21:40:13 INFO - 'LOGNAME': 'cltbld', 21:40:13 INFO - 'MAIL': '/var/mail/cltbld', 21:40:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:40:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:13 INFO - 'MOZ_NO_REMOTE': '1', 21:40:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:40:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:40:13 INFO - 'NO_EM_RESTART': '1', 21:40:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:13 INFO - 'PWD': '/builds/slave/test', 21:40:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:40:13 INFO - 'SHELL': '/bin/bash', 21:40:13 INFO - 'SHLVL': '1', 21:40:13 INFO - 'TERM': 'linux', 21:40:13 INFO - 'TMOUT': '86400', 21:40:13 INFO - 'USER': 'cltbld', 21:40:13 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710', 21:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:13 INFO - '_': '/tools/buildbot/bin/python'} 21:40:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 21:40:13 INFO - mozversion application_buildid: 20160419205830 21:40:13 INFO - mozversion application_changeset: d4cd977c44f0eaa7d7a6ec2f77481950e33833c6 21:40:13 INFO - mozversion application_display_name: Nightly 21:40:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:40:13 INFO - mozversion application_name: Firefox 21:40:13 INFO - mozversion application_remotingname: firefox 21:40:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:40:13 INFO - mozversion application_vendor: Mozilla 21:40:13 INFO - mozversion application_version: 48.0a1 21:40:13 INFO - mozversion platform_buildid: 20160419205830 21:40:13 INFO - mozversion platform_changeset: d4cd977c44f0eaa7d7a6ec2f77481950e33833c6 21:40:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:40:13 INFO - mozversion platform_version: 48.0a1 21:40:13 INFO - using testdate: 1461127213 21:40:13 INFO - actual date: 1461127213 21:40:13 INFO - starting webserver on 'localhost:55961' 21:40:13 INFO - SUITE-START | Running 2 tests 21:40:13 INFO - TEST-START | dromaeo_css 21:40:13 INFO - operating with platform_type : linux_ 21:40:13 INFO - Initialising browser for dromaeo_css test... 21:40:13 INFO - TEST-INFO | started process 5742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3hIuNF/profile http://localhost:55961/getInfo.html) 21:40:17 INFO - PROCESS | 5742 | 21:40:17 INFO - PROCESS | 5742 | (process:5809): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:40:17 INFO - PROCESS | 5742 | __metrics Screen width/height:1600/1200 21:40:17 INFO - PROCESS | 5742 | colorDepth:24 21:40:17 INFO - PROCESS | 5742 | Browser inner width/height: 1024/697 21:40:17 INFO - PROCESS | 5742 | __metrics 21:40:18 ERROR - PROCESS | 5742 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 21:40:19 INFO - TEST-INFO | 5742: exit 0 21:40:19 INFO - Browser initialized. 21:40:19 INFO - Running cycle 1/1 for dromaeo_css test... 21:40:19 INFO - TEST-INFO | started process 5860 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3hIuNF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:40:21 INFO - PROCESS | 5860 | 21:40:21 INFO - PROCESS | 5860 | (process:5921): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:40:31 INFO - PROCESS | 5860 | RSS: Main: 164634624 21:40:31 INFO - PROCESS | 5860 | 21:41:20 INFO - PROCESS | 5860 | 1461127280927 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] 21:43:32 INFO - PROCESS | 5860 | Cycle 1(1): loaded http://localhost:55961/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55961/tests/dromaeo/cssquery-ext.html) 21:43:33 INFO - PROCESS | 5860 | RSS: Main: 168681472 21:43:33 INFO - PROCESS | 5860 | 21:46:36 INFO - PROCESS | 5860 | Cycle 1(1): loaded http://localhost:55961/tests/dromaeo/cssquery-ext.html (next: http://localhost:55961/tests/dromaeo/cssquery-jquery.html) 21:46:36 INFO - PROCESS | 5860 | RSS: Main: 165597184 21:46:36 INFO - PROCESS | 5860 | 21:49:38 INFO - PROCESS | 5860 | Cycle 1(1): loaded http://localhost:55961/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55961/tests/dromaeo/cssquery-mootools.html) 21:49:39 INFO - PROCESS | 5860 | RSS: Main: 166531072 21:49:39 INFO - PROCESS | 5860 | 21:52:41 INFO - PROCESS | 5860 | Cycle 1(1): loaded http://localhost:55961/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55961/tests/dromaeo/cssquery-prototype.html) 21:52:41 INFO - PROCESS | 5860 | RSS: Main: 164077568 21:52:41 INFO - PROCESS | 5860 | 21:55:44 INFO - PROCESS | 5860 | Cycle 1(1): loaded http://localhost:55961/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55961/tests/dromaeo/cssquery-yui.html) 21:55:44 INFO - PROCESS | 5860 | RSS: Main: 165138432 21:55:44 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | Cycle 1(1): loaded http://localhost:55961/tests/dromaeo/cssquery-yui.html (next: http://localhost:55961/tests/dromaeo/cssquery-dojo.html) 21:58:47 INFO - PROCESS | 5860 | RSS: Main: 167690240 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | __start_tp_report 21:58:47 INFO - PROCESS | 5860 | _x_x_mozilla_page_load 21:58:47 INFO - PROCESS | 5860 | _x_x_mozilla_page_load_details 21:58:47 INFO - PROCESS | 5860 | |i|pagename|runs| 21:58:47 INFO - PROCESS | 5860 | |0;dojo.html;2610.5953577195532;2676.330917270682;2707.6344693466376;2716.551768958122;2718.7417195366447;4642.094791515651;4682.789274482648;4705.788239529221;4719.362886010387;4726.85819425417;4574.565416285456;4685.25035994241;4735.218688916325;4750.7149571025675;4794.352762377078;4326.805293761772;4418.160549495602;4418.226810308183;4418.82324707013;4423.0711550574415;582.2860072380016;4268.125034367963;4333.2416827055295;4403.141387429452;4443.533428989958;4350.543192964725;4383.101464199864;4394.868153955386;4409.933850992237;4444.844430444951;4210.5157906840495;4451.5103338632725;4462.977685111586;4475.731456112644;4519.028408892071;4300.118475712471;4331.090471001094;4331.501877284121;4332.913341733148;4395.714278571914;4582.633389328846;4628.190066738307;4635.072985402933;4641.071785642885;4705.082508910764;4967.379077615298;4984.152694042022;5006.224035274538;5014.824481143142;5067.239914012891;5117.360329958755;5120.564751996085;5126.5898728101665;5127.948720512784;5135.512126347991;4752.548507891814;4778.737169455713;4783.258594917794;4789.928151077736;4823.686460380064;4961.578265847444;5153.510416510426;5250.291210686509;5262.342207224097;5267.552258058031;4937.901241975141;4954.752262386866;4956.107900577858;4968.975155124273;5025.095482813129;5062.0382127396415;5063;5065.265536497261;5069.062223488666;5089.440161582223;4834.299026641117;5030.8239211627415;5061;5101.158308878993;5120.411152717411;4805.207140821795;4930.531599498042;4973.2540118981715;4974.5274198951765;5030.597552195815;4250.936235956463;4333.458317710286;4341.305391137403;4375.212461756914;4382.035952090535;3647.7264205184715;3685.686716629063;3744.756590821588;3765.849366025383;3785.545734511876;4417.911641767211;4427.734335939854;4434.800433980478;4450.755208463565;4468.32975053738;4932.605391568666;5102;5128.8717782055;5134.204198349262;5142.382914050338;4343.67422443318;4389.561043895585;4395.120975804788;4428.734275943454;4442.111577684476;4473.619742321874;4474.418325617649;4482.21561226784;4523.592876641118;4537.001859590885;4695.084458530622;4704.811807527661;4707.693999889996;4719.10337035962;4871.585915197106;5012.223105418666;5047.697138171721;5050.747462626928;5052.368453943257;5103.413107492686;4756.7145971241835;4766.428028636586;4768.809247630056;4774.30772537987;4829.420469543677;4821.807127714852;4983.07813054586;5056.6713163644245;5073.239014147907;5088.135017047037;5066.620003499678;5089.211172268304;5117.641765076409;5127.230915362726;5182.222666600041;4929.211326187793;4953.356063711694;4954.529319714621;4956.529129732669;4964.982178653439;4759.6668233223345;4924.72913989734;4971.6022718183185;4988.226824842155;5037.899242015066;4635.420572428447;4856.955754512655;4890.535399137077;4899.289603007473;4955.776990035529;5006.849794506171;5036.445990940921;5049.2426136079885;5057.873553161053;5084.644074914869;4702.694324868872;4877.926831097466;4911.3369695090705;4945.530174633404;4967.975160124176 21:58:47 INFO - PROCESS | 5860 | |1;ext.html;590.5865893874286;608.2366629879502;624.5347216323838;639.827246643406;650.304174533249;35538.466922996144;36608;36692.44961325582;36739.26521469569;36987;35576;36128.37422315995;36335.81832090842;36423.817880910625;36450;7567.129780075293;7798.961005194995;7817.374610031184;7824.452288339819;7828.647710853025;7557;7616.885746713804;7678.193789652089;7687.115981662097;7691.269329413724;7647.082350117966;7703.46075774696;7708.498947568418;7711.961440192791;7714.807129821771;7605.505642133244;7615.124260710034;7637.961810190969;7646.732364367221;7664.885026724603;7675.117361503443;7775.650095745705;7777.766666999943;7787.143414224431;7821.061472623264;29296.267593310124;34948;35342.46986295208;35374.11564710903;35593.466098008554;2878.4962631539606;3063.9080827574985;3370.7977521348553;3375.8480868360984;3461.705755010827;27045.188644340702;27229;27305.863470682718;27499.45001099987;27665.170044898685;3328.1014126185796;3365.3101114271763;3415.9487607685905;3476;3524.400851855191;225298;232051;232079;232968;233237.83381083157;56424.15363769547;57395.42604573984;57454;57550.13674794881;57560.42439575551;68457.31542684509;70015;70331.29668703247;70845;70921.29078709146;14075.507357242448;14266.929980251523;14280.214588197749;14306.427742890168;14318.355673994694;10464.11055060313;10549.103326217204;10560.102391296672;10560.841587376195;10587.947060264803;19484.415467535997;19693.803061969196;19786.307479238156;19799.505012374804;19920.3027894023;7283.854322913512;7492.438067144986;7637.541747495168;7659.0981771666175;7769.223077692299;910.977225569366;915.8122584870085;922.3359181389388;1021.1728499915092;1051.1275641217771;27646.170614881587;28148.577771333446;28863.567046494318;28913.277168070967;29097.127086187447;1004.3070281505737;1005.7686732051669;1034.4310629153936;1090.2150451674781;1115.893990070942;14710.485133020293;15243.542693719206;15290.617734556725;15457.84542154564;15515.612109697347;320855;325452;327312;328604;329248;47757;48244.758776206596;48267;48523.272150917765;48645.75677121663;1195.5636192789568;1248.431963456625;1249.8375211222665;1252.4864805429913;1265.3040827544814;26797;27682.615869206056;27793.58309625317;28224.15327540177;28269.57595636108;41946;42402.363964541175;42578.36132458078;42824.92937676583;42829.78585107055;299056;300029;300231;301182;301399;25897.870510647324;26187.214383568524;26259.737402625728;26426.339341516617;26485.33786655349;42245.1550968985;42516.787416062725;42956.14087718291;43188.568114319714;43208.135837283706;35464.46803297901;35931.64068359283;36613.81693091518;36685;36697.44953825748;35483.82258088693;36095.639043609226;36490;36687.63312366842;36879.8156009229;35710.28579428345;35737;36532.63467365292;37791;37845.43231851469;27394.5890811642;29506.852465737535;29915.700842991293;31789.523157152198;32232.516512251867;24887.502249954538;25002;25103.246902592953;25255.242342729747;25491 21:58:47 INFO - PROCESS | 5860 | |2;jquery.html;383.6278809554731;386.15432203474387;389.4139320322914;400.4553806822722;404.1895998522961;13116.147450415716;14888.77666834997;15990.600234994132;16586.834131658663;16599.834001659994;15725.60685982851;16029.278682459288;16297;16868.325266989305;16916.577085572837;4955.801767929279;4956.776945037482;5133.255677926699;5138.460461651529;5155.072087024334;5206.140986737184;5206.427292997767;5224.059669259532;5230.450802665722;5234.031704134728;5217.765200565965;5410.134378499441;6085.604435711664;6087.78692745754;6096.725647345858;5637.295338082739;6014.729337179838;6021.578489505737;6024.246969128859;6051.243594550681;5394.595405344615;5411.161270003116;5432.049391356536;5436.565074794007;5443.020256353855;12016.339101349422;12210.32843193624;13347.532836350778;20016.499587510283;20077.196912123498;1810.6740786658397;1944.5819148882917;1962.8822270663807;2185.9344219673276;2294.66727324537;8999.730008099768;9097.545122743837;9145.45127292358;9420.199283060949;9515.381500202464;1744.799348074975;1774.8225177482277;1825.771778527684;1840.788309344429;1844.732513785507;65932;66182.66908665426;67462.3253767461;67905.3209467904;67930.66034669794;9846.901530984598;9848.2613803965;9849.606015759433;9919.80160396788;9958.253131015057;11858;12688;13598.456061757419;13637.181769093886;13676.521321753691;6790.660466976631;6865.862682746317;6884.449244060464;6991.93008069923;7010.964945175344;6442.549021568539;6467.579607325603;6516.0877477153235;6538.051982462516;6543.476521878239;4138.751674899516;4192.685548583869;4259.446271984684;4265.296226122655;4344.565543445693;1795.2908601102492;1811.664842004207;1816.8637352198639;1821.7176337667684;1862.990685046566;883.1345281624044;887.4231749362966;939.2439086535268;968.4237878462303;1046.5499835070993;14442.700156985922;16138.273777680033;16220.59448513773;16327;16503.752443713354;869.9652013919373;913.3789023463976;915.1077699243318;916.9449833010041;923.7967647117623;8968.955155224183;9245.8938489411;9284.907150928539;9380.95309523448;9509.429434233829;369154;370874;370973;374949;379371;46625.766871165426;54183.45816541784;67765.66117169481;73663.63168184125;75652.62173689206;928.7538802217417;949.1220620925616;957.9856302155334;961.4087336288272;963.0754475703403;14258.71482570322;14362.640933976734;14624.415023398944;14630.178548259912;14933.55199343978;25674.743252567234;31676;31930.521042183922;32858.17854553559;35573.644263557035;41587.7920610395;41739.791301043304;41875.7906210467;42759;43167.56832431635;7760.3403710685225;7891.408144389308;7928.445008849325;7938.563379013978;7996.480228785111;11101.833472498081;11372.488238029475;11603.709907252387;11614.88385116138;11821.70445738829;9408.717738467856;9553.761155971157;9914.752131196488;10128.74678133023;10220.540075696463;9760.072793084646;9841.704748857546;10174.389536627827;10307.845382319421;10399.428031458343;5060.696358218519;9997.000299969945;10269.948650256702;10304.845427318447;10390.896091038992;7551.546907185587;7571;7582.355499782359;7611.4291428144215;7739.18738532446;7831.686732530635;9943.502824858873;10203.846942295722;10211.489425528842;10219.7445063874 21:58:47 INFO - PROCESS | 5860 | |3;mootools.html;1463.4804644351252;1495.5961890289623;1634.403442743399;1759.5689056181238;1824.7171688388296;6957.860842783141;7666.961665191666;7733.149353571102;7991.280784729373;8023.598820058995;7622.733204337849;7785.455018148732;7788.766337009884;7806.609669516516;7877.291043806056;2253.4591697992446;2284.097781376355;2295.4605667668084;2312.583734927712;2315.5253173099472;2201.3395981205654;2204.0522575292616;2262.7963483286503;2269.614165591845;2275.0444813178424;2252.4706693926914;2283.737370202423;2285.314405678298;2302.7697230277004;2308.3651995701234;2231.620624493824;2257.4243567890167;2261.378121016717;2264.5923733727923;2271.4775601611555;2242.316093591454;2257.401788526042;2259.45773014477;2261.9095236190697;2286.634138537843;8024.719134830311;8086.595670216466;8089.555074470902;8104.027516697975;8143.144969778147;4694.600958918496;4838.879028024292;4843.4187897452175;4846.030793841246;4860.951390486085;2846.501862174132;2909.3453972855937;2927.0926012935847;2940.1767505098605;2946.5727469517014;1601.9038857668577;1623.2938671677857;1629.291258302642;1639.9918000410041;1695.8643308535288;10499.160067194682;10758.354498730101;10787.298825576312;10789.460526973699;10821.513031913582;4307.741535507879;4389.429374181336;4438.844640437569;4444.533324000998;4449.577290157495;8476.406651534457;8504.489730616058;8524.14758524155;8532.53071081084;8534;4718.41019872516;4732.195526760459;4746.95253047472;4762.499937506581;4775.808967641325;3778.6032466591164;4266.445362102907;4399.49405818335;4411.669124815651;4449.844255451108;3592.76647017943;3646.6171052039685;3722.5346831646043;3737.5141231639714;3754.136548593784;1121.9270747401392;1137.624583887315;1140.5836869542557;1155.4742592120565;1164.7088227943013;791.4815795653856;906.8549032154824;911.9270458363314;922.5202894494826;935.9953200233955;5249.50129737682;5303.310569625924;5358.33020872391;5492.80775172875;5554.91667624986;413.98965025874594;422.5014482910195;429.5790125676854;429.90112274177113;432.36010704158025;3734.8879533614036;3921.3333733265413;3950.20995800841;3965.88102356924;3969;8939.329550283624;9264.351495395258;9295.163435290857;9296;9327.766805829773;9067.591958361889;9083.863742043875;9256.907430925605;9670.75823104428;9691;7230.240824713434;7380.225076367012;7422.109346878409;7435.628218589049;7437.702491900263;5656.406077361818;5793.884122317614;5900.203472531153;5909.793157239476;6012.068129439944;9122;9180.311476639145;9194.540272986458;9222.538873056454;9247.953760231156;8676.91323086761;8869.73390798277;8880.245179159585;8948.821023579361;9019.774505637411;6106.297775755845;6594.142761440982;6763.93236067633;6779.423748981392;7021.613811240431;7259.854802903807;7261.237570055279;7311.086114235721;7320.743773967885;7323.487355885037;6506.512011599054;6664.600123992576;6698.966505167443;6712.2952090031795;6830.556013859084;6564.4748420125325;6650.035744816878;6666.899996500153;6698.39714425704;6750.2237242715755;6332.0818481320875;6382.6170429774365;6419.390157934989;6434.742610295723;6467.741290348334;4758.119747846659;4790.377250957354;4887.828925987716;4916.385451818523;4927.039227350632;4517.209488339593;4624.953750462587;4728.668993170445;4733.9763301183275;4743.264793956942 21:58:47 INFO - PROCESS | 5860 | |4;prototype.html;311.3663694381933;321.79565975605493;323.0518428412608;324.3464419195321;330.9420851351014;5035.798568057283;5163.948360516394;5217.9739101304485;5294.92057619136;5311.62818602698;5223.791048358071;5261.86845328867;5262.526372626463;5263.973680131604;5281.419043905167;4114.732542384751;4117.353058776491;4123.628873401393;4126.8968275793195;4135.710500264982;4066.755994640331;4074.7147699661036;4116.629503344699;4134.338505839067;4146.3158578834455;4054.5738850671282;4075.470188875442;4099.6720262379085;4102.3026085565525;4102.733322334038;4090.365993271048;4092.263392589318;4104.1381309924955;4105.261053010457;4117.5882411758585;3633.5639723233116;3636.090977255686;3644.5990940996735;3649.2154186849953;3661.029827095822;4955.256711493269;4985.202367621163;5052.317937078484;5055.34280543527;5063.594912407036;4641.466231383401;4685.648576356787;4698.154332220199;4712.670113092085;4722.858314250544;5493.901219756065;5514.724263786835;5515.227868098469;5527.2538207341895;5530.11518157097;3823.5220597425323;3954.130091379892;3958.9208215835524;3961.861334853295;3979.5622481527007;5753.654780713129;5760.567957403212;5783.913241301384;5803.941960580382;5823.165702803603;4069.064115253508;4073.165001174752;4090.3250963590676;4090.8159132839096;4096.078382363944;4028.436018957348;4141.358089496133;4149.522804877417;4151.543330233671;4155.97922010388;4678.719276843401;4679.344891715163;4692.9530704692515;4694.225452800318;4708.317293992352;5183.429822719498;5486.945130548724;5547.278853748987;5548.528375088082;5572.3870374258795;1388.0145096981257;1426.472205284031;1430.4135304525096;1435.9928200358931;1446;973.2992245583168;976.1556253840376;998.9600415983425;1010.7119470950744;1013.1793247469426;4751.40607424072;4767.356406885026;4787.3776409067295;4791.305260737243;4793.209120495149;5630.634008789498;5649.406812284733;5661.34894487131;5669.716514174274;5671.234383358276;4649.442066951988;4779.426468823764;4781.808727650855;4797.376341075638;4799.808007679723;3002.879884804627;3010.5935698680837;3015.6079709637606;3043.6347638283546;3051.5575241589845;5310.707911064928;5334.333208348957;5340.706261155596;5343.118385466432;5350.946490535122;5597.272354593877;5758.596898217168;5759.568032397503;5784.59507834456;5785.132230165509;5318.97340513295;5322.41453440129;5328.573714102862;5346.037713211582;5355.598330125256;5397.271368365377;5599.776008959759;5620.831375058755;5643.181738647955;5646.717664116695;5499.642523235977;5501.5323697486165;5532.336119665505;5545.750441230235;5552.278203833475;5289.788408463773;5295.841124766263;5312.150055991023;5325.041492531382;5333.626646134733;3769.4722738817136;4061.7156799024965;4066.512018557792;4086.6117718816663;4087.3051581231857;5469.890602187855;5609.27079479665;5709.286339207599;5741.224934633938;5741.3971532988435;5573.247611572548;5607.691576963143;5609.411011843697;5623.775048997994;5648.491635752802;5494.230807686847;5569.637973531708;5593.188987596857;5596.608237423341;5597.3003374578175;5172.5344718975475;5435.673859568438;5483.61614686984;5484.561235101262;5484.7806087757635;5596.244506991667;5640.351359593699;5649.237352957298;5662.745176466987;5668.971655141698;5199.76601052946;5483.7258137092185;5518.530924871431;5530.364008139116;5545.445455454423 21:58:47 INFO - PROCESS | 5860 | |5;yui.html;683.5795985468939;687.7730348984835;703.8240439890028;712.2094475132602;717.7810767715845;10872.303456653672;10969.451527423627;11063.446827658625;11163.944180279108;11200.943995280022;10919.344839309628;11019.118470522359;11129.499172537235;11146.777064458705;11358.772824543505;644.1915396177814;790.9090454597738;802.5545822068749;818.4557269415832;821.0393839208111;812.3257696112242;812.585581353511;812.6911773526082;812.9227723366271;813.7436707437139;840.5965136734371;841.9536925469097;845.049319515545;845.2772879188298;846.6232526525698;836.4855613797531;838.6393850644221;838.8532006898829;840.4789030800912;840.8528507511161;606.65723866016;614.1862032806549;616.1743264026181;617.7096764520668;618.1068356225211;11446.370449625267;11462;11509.136814738928;11528.135389845711;11562.595309164222;12185.756284874253;12560.246385216917;12664.746705065847;13039.826415622545;13118.278494682789;11298.322100673986;11386.202965792398;11409.14431417647;11469.426528673534;11526.62510423699;4066.105456799529;4079.5104587449696;4151.46031015969;4164.1879833432495;4187.979060104711;47083;47403.76298118522;48337.51662483365;49237;49352.75323623395;10119.848202276971;10277.537510812032;10328.793424131476;10400.47997600117;10439.686809395731;16919;18599.07004649762;18600;18625.789323693916;18639.627207456047;6066.575339726177;6095.391835941509;6127.785527506605;6129.233845769279;6210.472109870621;3264.3634491273974;3413.74396920232;3453.2920751245933;3461.048211741791;3514.7715398499017;2719.1706529508688;2807.9157625271273;2812.0860720265996;2828.179827849942;2837.929051773681;502.6682389622831;503.2099603622278;506.6099103690138;513.1456125550991;514.578046002282;527.6385675812081;577.1861675038176;579.8666306749471;579.9971000144973;599.4485073732177;7349.853002940018;7362.779116626402;7554.811129721691;7763.650635721406;7790.610469476617;496.61016102360196;545.6371512943928;586.357938057826;597.6444015810586;603.6257520337331;3585.1216451969435;3631.673149416565;3670.6145854685406;3696.759710618855;3732.42147467143;49944.750276248385;52132.47867521352;52241.47758522442;52293.73853130786;52462.213066804026;43713.78143109264;45150.32274515885;45346.093078138256;45661.77169114133;46509;42156.367654485206;42347.78826105911;42619.147617047485;42929.57070429256;43163.784181078896;6997.370236678723;7164.104486939133;7180.60506672138;7183.102112235971;7316.195218526064;28168.71831281661;28295.717042829307;28537.714622853506;29111.272218193866;29341;35587.82206088953;37327.25345493021;37347;37503.624963750015;37655.62344376521;9367.250619950524;9388.57751401175;9483.905160948303;9541.618335266789;9697.854532181882;10096.394216347042;10268.754891180508;10413.583456661952;10473.423961682181;10688.25182237236;8691.608877600396;8754.956225218833;8827.514486703292;9010.27917766564;9098.317626178195;8274.793130171794;8336.124706905688;8347.290480309242;8381.49711017341;8431.662733490837;9129;9668.809778941893;10709.678709638723;10971.890281097087;11059.115270778157;9150.542472876463;9381.812363752822;9482.620695172393;9647.517624118625;9758.170555502862;8539.658413663385;8821.382503224713;9010.909890901006;9110.180083792491;9115.680951166678 21:58:47 INFO - PROCESS | 5860 | __end_tp_report 21:58:47 INFO - PROCESS | 5860 | __start_cc_report 21:58:47 INFO - PROCESS | 5860 | _x_x_mozilla_cycle_collect,363 21:58:47 INFO - PROCESS | 5860 | __end_cc_report 21:58:47 INFO - PROCESS | 5860 | __startTimestamp1461128327654__endTimestamp 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | ------- Summary: start ------- 21:58:47 INFO - PROCESS | 5860 | Number of tests: 6 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | [#0] dojo.html Cycles:165 Average:4662.07 Median:4789.93 stddev:579.60 (12.1%) stddev-sans-first:558.59 21:58:47 INFO - PROCESS | 5860 | Values: 2610.6 2676.3 2707.6 2716.6 2718.7 4642.1 4682.8 4705.8 4719.4 4726.9 4574.6 4685.3 4735.2 4750.7 4794.4 4326.8 4418.2 4418.2 4418.8 4423.1 582.3 4268.1 4333.2 4403.1 4443.5 4350.5 4383.1 4394.9 4409.9 4444.8 4210.5 4451.5 4463.0 4475.7 4519.0 4300.1 4331.1 4331.5 4332.9 4395.7 4582.6 4628.2 4635.1 4641.1 4705.1 4967.4 4984.2 5006.2 5014.8 5067.2 5117.4 5120.6 5126.6 5127.9 5135.5 4752.5 4778.7 4783.3 4789.9 4823.7 4961.6 5153.5 5250.3 5262.3 5267.6 4937.9 4954.8 4956.1 4969.0 5025.1 5062.0 5063.0 5065.3 5069.1 5089.4 4834.3 5030.8 5061.0 5101.2 5120.4 4805.2 4930.5 4973.3 4974.5 5030.6 4250.9 4333.5 4341.3 4375.2 4382.0 3647.7 3685.7 3744.8 3765.8 3785.5 4417.9 4427.7 4434.8 4450.8 4468.3 4932.6 5102.0 5128.9 5134.2 5142.4 4343.7 4389.6 4395.1 4428.7 4442.1 4473.6 4474.4 4482.2 4523.6 4537.0 4695.1 4704.8 4707.7 4719.1 4871.6 5012.2 5047.7 5050.7 5052.4 5103.4 4756.7 4766.4 4768.8 4774.3 4829.4 4821.8 4983.1 5056.7 5073.2 5088.1 5066.6 5089.2 5117.6 5127.2 5182.2 4929.2 4953.4 4954.5 4956.5 4965.0 4759.7 4924.7 4971.6 4988.2 5037.9 4635.4 4857.0 4890.5 4899.3 4955.8 5006.8 5036.4 5049.2 5057.9 5084.6 4702.7 4877.9 4911.3 4945.5 4968.0 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | [#1] ext.html Cycles:180 Average:44903.98 Median:35419.29 stddev:75745.41 (213.9%) stddev-sans-first:75884.82 21:58:47 INFO - PROCESS | 5860 | Values: 590.6 608.2 624.5 639.8 650.3 35538.5 36608.0 36692.4 36739.3 36987.0 35576.0 36128.4 36335.8 36423.8 36450.0 7567.1 7799.0 7817.4 7824.5 7828.6 7557.0 7616.9 7678.2 7687.1 7691.3 7647.1 7703.5 7708.5 7712.0 7714.8 7605.5 7615.1 7638.0 7646.7 7664.9 7675.1 7775.7 7777.8 7787.1 7821.1 29296.3 34948.0 35342.5 35374.1 35593.5 2878.5 3063.9 3370.8 3375.8 3461.7 27045.2 27229.0 27305.9 27499.5 27665.2 3328.1 3365.3 3415.9 3476.0 3524.4 225298.0 232051.0 232079.0 232968.0 233237.8 56424.2 57395.4 57454.0 57550.1 57560.4 68457.3 70015.0 70331.3 70845.0 70921.3 14075.5 14266.9 14280.2 14306.4 14318.4 10464.1 10549.1 10560.1 10560.8 10587.9 19484.4 19693.8 19786.3 19799.5 19920.3 7283.9 7492.4 7637.5 7659.1 7769.2 911.0 915.8 922.3 1021.2 1051.1 27646.2 28148.6 28863.6 28913.3 29097.1 1004.3 1005.8 1034.4 1090.2 1115.9 14710.5 15243.5 15290.6 15457.8 15515.6 320855.0 325452.0 327312.0 328604.0 329248.0 47757.0 48244.8 48267.0 48523.3 48645.8 1195.6 1248.4 1249.8 1252.5 1265.3 26797.0 27682.6 27793.6 28224.2 28269.6 41946.0 42402.4 42578.4 42824.9 42829.8 299056.0 300029.0 300231.0 301182.0 301399.0 25897.9 26187.2 26259.7 26426.3 26485.3 42245.2 42516.8 42956.1 43188.6 43208.1 35464.5 35931.6 36613.8 36685.0 36697.4 35483.8 36095.6 36490.0 36687.6 36879.8 35710.3 35737.0 36532.6 37791.0 37845.4 27394.6 29506.9 29915.7 31789.5 32232.5 24887.5 25002.0 25103.2 25255.2 25491.0 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | [#2] jquery.html Cycles:180 Average:22683.66 Median:5096.98 stddev:61341.08 (1203.5%) stddev-sans-first:61490.30 21:58:47 INFO - PROCESS | 5860 | Values: 383.6 386.2 389.4 400.5 404.2 13116.1 14888.8 15990.6 16586.8 16599.8 15725.6 16029.3 16297.0 16868.3 16916.6 4955.8 4956.8 5133.3 5138.5 5155.1 5206.1 5206.4 5224.1 5230.5 5234.0 5217.8 5410.1 6085.6 6087.8 6096.7 5637.3 6014.7 6021.6 6024.2 6051.2 5394.6 5411.2 5432.0 5436.6 5443.0 12016.3 12210.3 13347.5 20016.5 20077.2 1810.7 1944.6 1962.9 2185.9 2294.7 8999.7 9097.5 9145.5 9420.2 9515.4 1744.8 1774.8 1825.8 1840.8 1844.7 65932.0 66182.7 67462.3 67905.3 67930.7 9846.9 9848.3 9849.6 9919.8 9958.3 11858.0 12688.0 13598.5 13637.2 13676.5 6790.7 6865.9 6884.4 6991.9 7011.0 6442.5 6467.6 6516.1 6538.1 6543.5 4138.8 4192.7 4259.4 4265.3 4344.6 1795.3 1811.7 1816.9 1821.7 1863.0 883.1 887.4 939.2 968.4 1046.5 14442.7 16138.3 16220.6 16327.0 16503.8 870.0 913.4 915.1 916.9 923.8 8969.0 9245.9 9284.9 9381.0 9509.4 369154.0 370874.0 370973.0 374949.0 379371.0 46625.8 54183.5 67765.7 73663.6 75652.6 928.8 949.1 958.0 961.4 963.1 14258.7 14362.6 14624.4 14630.2 14933.6 25674.7 31676.0 31930.5 32858.2 35573.6 41587.8 41739.8 41875.8 42759.0 43167.6 7760.3 7891.4 7928.4 7938.6 7996.5 11101.8 11372.5 11603.7 11614.9 11821.7 9408.7 9553.8 9914.8 10128.7 10220.5 9760.1 9841.7 10174.4 10307.8 10399.4 5060.7 9997.0 10269.9 10304.8 10390.9 7551.5 7571.0 7582.4 7611.4 7739.2 7831.7 9943.5 10203.8 10211.5 10219.7 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | [#3] mootools.html Cycles:180 Average:5197.65 Median:4853.49 stddev:2803.14 (57.8%) stddev-sans-first:2796.96 21:58:47 INFO - PROCESS | 5860 | Values: 1463.5 1495.6 1634.4 1759.6 1824.7 6957.9 7667.0 7733.1 7991.3 8023.6 7622.7 7785.5 7788.8 7806.6 7877.3 2253.5 2284.1 2295.5 2312.6 2315.5 2201.3 2204.1 2262.8 2269.6 2275.0 2252.5 2283.7 2285.3 2302.8 2308.4 2231.6 2257.4 2261.4 2264.6 2271.5 2242.3 2257.4 2259.5 2261.9 2286.6 8024.7 8086.6 8089.6 8104.0 8143.1 4694.6 4838.9 4843.4 4846.0 4861.0 2846.5 2909.3 2927.1 2940.2 2946.6 1601.9 1623.3 1629.3 1640.0 1695.9 10499.2 10758.4 10787.3 10789.5 10821.5 4307.7 4389.4 4438.8 4444.5 4449.6 8476.4 8504.5 8524.1 8532.5 8534.0 4718.4 4732.2 4747.0 4762.5 4775.8 3778.6 4266.4 4399.5 4411.7 4449.8 3592.8 3646.6 3722.5 3737.5 3754.1 1121.9 1137.6 1140.6 1155.5 1164.7 791.5 906.9 911.9 922.5 936.0 5249.5 5303.3 5358.3 5492.8 5554.9 414.0 422.5 429.6 429.9 432.4 3734.9 3921.3 3950.2 3965.9 3969.0 8939.3 9264.4 9295.2 9296.0 9327.8 9067.6 9083.9 9256.9 9670.8 9691.0 7230.2 7380.2 7422.1 7435.6 7437.7 5656.4 5793.9 5900.2 5909.8 6012.1 9122.0 9180.3 9194.5 9222.5 9248.0 8676.9 8869.7 8880.2 8948.8 9019.8 6106.3 6594.1 6763.9 6779.4 7021.6 7259.9 7261.2 7311.1 7320.7 7323.5 6506.5 6664.6 6699.0 6712.3 6830.6 6564.5 6650.0 6666.9 6698.4 6750.2 6332.1 6382.6 6419.4 6434.7 6467.7 4758.1 4790.4 4887.8 4916.4 4927.0 4517.2 4625.0 4728.7 4734.0 4743.3 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | [#4] prototype.html Cycles:180 Average:4583.33 Median:5191.60 stddev:1321.97 (25.5%) stddev-sans-first:1286.20 21:58:47 INFO - PROCESS | 5860 | Values: 311.4 321.8 323.1 324.3 330.9 5035.8 5163.9 5218.0 5294.9 5311.6 5223.8 5261.9 5262.5 5264.0 5281.4 4114.7 4117.4 4123.6 4126.9 4135.7 4066.8 4074.7 4116.6 4134.3 4146.3 4054.6 4075.5 4099.7 4102.3 4102.7 4090.4 4092.3 4104.1 4105.3 4117.6 3633.6 3636.1 3644.6 3649.2 3661.0 4955.3 4985.2 5052.3 5055.3 5063.6 4641.5 4685.6 4698.2 4712.7 4722.9 5493.9 5514.7 5515.2 5527.3 5530.1 3823.5 3954.1 3958.9 3961.9 3979.6 5753.7 5760.6 5783.9 5803.9 5823.2 4069.1 4073.2 4090.3 4090.8 4096.1 4028.4 4141.4 4149.5 4151.5 4156.0 4678.7 4679.3 4693.0 4694.2 4708.3 5183.4 5486.9 5547.3 5548.5 5572.4 1388.0 1426.5 1430.4 1436.0 1446.0 973.3 976.2 999.0 1010.7 1013.2 4751.4 4767.4 4787.4 4791.3 4793.2 5630.6 5649.4 5661.3 5669.7 5671.2 4649.4 4779.4 4781.8 4797.4 4799.8 3002.9 3010.6 3015.6 3043.6 3051.6 5310.7 5334.3 5340.7 5343.1 5350.9 5597.3 5758.6 5759.6 5784.6 5785.1 5319.0 5322.4 5328.6 5346.0 5355.6 5397.3 5599.8 5620.8 5643.2 5646.7 5499.6 5501.5 5532.3 5545.8 5552.3 5289.8 5295.8 5312.2 5325.0 5333.6 3769.5 4061.7 4066.5 4086.6 4087.3 5469.9 5609.3 5709.3 5741.2 5741.4 5573.2 5607.7 5609.4 5623.8 5648.5 5494.2 5569.6 5593.2 5596.6 5597.3 5172.5 5435.7 5483.6 5484.6 5484.8 5596.2 5640.4 5649.2 5662.7 5669.0 5199.8 5483.7 5518.5 5530.4 5545.4 21:58:47 INFO - PROCESS | 5860 | 21:58:47 INFO - PROCESS | 5860 | [#5] yui.html Cycles:180 Average:12419.96 Median:502.94 stddev:14443.14 (2871.7%) stddev-sans-first:14456.77 21:58:47 INFO - PROCESS | 5860 | Values: 683.6 687.8 703.8 712.2 717.8 10872.3 10969.5 11063.4 11163.9 11200.9 10919.3 11019.1 11129.5 11146.8 11358.8 644.2 790.9 802.6 818.5 821.0 812.3 812.6 812.7 812.9 813.7 840.6 842.0 845.0 845.3 846.6 836.5 838.6 838.9 840.5 840.9 606.7 614.2 616.2 617.7 618.1 11446.4 11462.0 11509.1 11528.1 11562.6 12185.8 12560.2 12664.7 13039.8 13118.3 11298.3 11386.2 11409.1 11469.4 11526.6 4066.1 4079.5 4151.5 4164.2 4188.0 47083.0 47403.8 48337.5 49237.0 49352.8 10119.8 10277.5 10328.8 10400.5 10439.7 16919.0 18599.1 18600.0 18625.8 18639.6 6066.6 6095.4 6127.8 6129.2 6210.5 3264.4 3413.7 3453.3 3461.0 3514.8 2719.2 2807.9 2812.1 2828.2 2837.9 502.7 503.2 506.6 513.1 514.6 527.6 577.2 579.9 580.0 599.4 7349.9 7362.8 7554.8 7763.7 7790.6 496.6 545.6 586.4 597.6 603.6 3585.1 3631.7 3670.6 3696.8 3732.4 49944.8 52132.5 52241.5 52293.7 52462.2 43713.8 45150.3 45346.1 45661.8 46509.0 42156.4 42347.8 42619.1 42929.6 43163.8 6997.4 7164.1 7180.6 7183.1 7316.2 28168.7 28295.7 28537.7 29111.3 29341.0 35587.8 37327.3 37347.0 37503.6 37655.6 9367.3 9388.6 9483.9 9541.6 9697.9 10096.4 10268.8 10413.6 10473.4 10688.3 8691.6 8755.0 8827.5 9010.3 9098.3 8274.8 8336.1 8347.3 8381.5 8431.7 9129.0 9668.8 10709.7 10971.9 11059.1 9150.5 9381.8 9482.6 9647.5 9758.2 8539.7 8821.4 9010.9 9110.2 9115.7 21:58:47 INFO - PROCESS | 5860 | -------- Summary: end -------- 21:58:47 INFO - PROCESS | 5860 | 21:58:48 INFO - TEST-INFO | 5860: exit 0 21:58:48 INFO - TEST-OK | dromaeo_css | took 1115156ms 21:58:48 INFO - TEST-START | kraken 21:58:48 INFO - operating with platform_type : linux_ 21:58:48 INFO - Initialising browser for kraken test... 21:58:48 INFO - TEST-INFO | started process 6094 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFBDlgB/profile http://localhost:55961/getInfo.html) 21:58:52 INFO - PROCESS | 6094 | 21:58:52 INFO - PROCESS | 6094 | (process:6163): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:58:52 INFO - PROCESS | 6094 | __metrics Screen width/height:1600/1200 21:58:52 INFO - PROCESS | 6094 | colorDepth:24 21:58:52 INFO - PROCESS | 6094 | Browser inner width/height: 1024/697 21:58:52 INFO - PROCESS | 6094 | __metrics 21:58:53 ERROR - PROCESS | 6094 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 21:58:54 INFO - TEST-INFO | 6094: exit 0 21:58:54 INFO - Browser initialized. 21:58:54 INFO - Running cycle 1/1 for kraken test... 21:58:54 INFO - TEST-INFO | started process 6214 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFBDlgB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:58:56 INFO - PROCESS | 6214 | 21:58:56 INFO - PROCESS | 6214 | (process:6275): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:59:06 INFO - PROCESS | 6214 | RSS: Main: 164814848 21:59:06 INFO - PROCESS | 6214 | 21:59:09 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55961/tests/kraken/driver.html?testName=audio-beat-detection) 21:59:10 INFO - PROCESS | 6214 | RSS: Main: 169287680 21:59:10 INFO - PROCESS | 6214 | 21:59:12 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55961/tests/kraken/driver.html?testName=audio-dft) 21:59:13 INFO - PROCESS | 6214 | RSS: Main: 172531712 21:59:13 INFO - PROCESS | 6214 | 21:59:16 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55961/tests/kraken/driver.html?testName=audio-fft) 21:59:17 INFO - PROCESS | 6214 | RSS: Main: 163799040 21:59:17 INFO - PROCESS | 6214 | 21:59:19 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55961/tests/kraken/driver.html?testName=audio-oscillator) 21:59:19 INFO - PROCESS | 6214 | RSS: Main: 165167104 21:59:19 INFO - PROCESS | 6214 | 21:59:22 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55961/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:59:22 INFO - PROCESS | 6214 | RSS: Main: 165294080 21:59:22 INFO - PROCESS | 6214 | 21:59:25 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55961/tests/kraken/driver.html?testName=imaging-darkroom) 21:59:26 INFO - PROCESS | 6214 | RSS: Main: 164749312 21:59:26 INFO - PROCESS | 6214 | 21:59:30 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55961/tests/kraken/driver.html?testName=imaging-desaturate) 21:59:30 INFO - PROCESS | 6214 | RSS: Main: 164491264 21:59:30 INFO - PROCESS | 6214 | 21:59:33 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55961/tests/kraken/driver.html?testName=json-parse-financial) 21:59:34 INFO - PROCESS | 6214 | RSS: Main: 165052416 21:59:34 INFO - PROCESS | 6214 | 21:59:36 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55961/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:59:36 INFO - PROCESS | 6214 | RSS: Main: 165634048 21:59:36 INFO - PROCESS | 6214 | 21:59:38 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:59:39 INFO - PROCESS | 6214 | RSS: Main: 165679104 21:59:39 INFO - PROCESS | 6214 | 21:59:41 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:59:42 INFO - PROCESS | 6214 | RSS: Main: 166014976 21:59:42 INFO - PROCESS | 6214 | 21:59:44 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:59:45 INFO - PROCESS | 6214 | RSS: Main: 166285312 21:59:45 INFO - PROCESS | 6214 | 21:59:48 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:59:49 INFO - PROCESS | 6214 | RSS: Main: 166100992 21:59:49 INFO - PROCESS | 6214 | 21:59:50 INFO - PROCESS | 6214 | Cycle 1(1): loaded http://localhost:55961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55961/tests/kraken/driver.html?testName=ai-astar) 21:59:51 INFO - PROCESS | 6214 | RSS: Main: 168009728 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | __start_tp_report 21:59:51 INFO - PROCESS | 6214 | _x_x_mozilla_page_load 21:59:51 INFO - PROCESS | 6214 | _x_x_mozilla_page_load_details 21:59:51 INFO - PROCESS | 6214 | |i|pagename|runs| 21:59:51 INFO - PROCESS | 6214 | |0;ai-astar;114;108;114;112;113;124;112;113;123;114 21:59:51 INFO - PROCESS | 6214 | |1;audio-beat-detection;170;132;163;192;132;169;156;146;187;132 21:59:51 INFO - PROCESS | 6214 | |2;audio-dft;174;164;167;172;163;163;163;165;165;162 21:59:51 INFO - PROCESS | 6214 | |3;audio-fft;74;74;74;173;75;77;73;75;155;74 21:59:51 INFO - PROCESS | 6214 | |4;audio-oscillator;93;94;140;94;94;95;147;95;94;141 21:59:51 INFO - PROCESS | 6214 | |5;imaging-gaussian-blur;118;119;117;119;117;118;118;118;118;117 21:59:51 INFO - PROCESS | 6214 | |6;imaging-darkroom;147;148;147;148;147;148;146;146;147;148 21:59:51 INFO - PROCESS | 6214 | |7;imaging-desaturate;95;96;95;100;95;96;96;95;96;95 21:59:51 INFO - PROCESS | 6214 | |8;json-parse-financial;64;65;64;64;64;71;64;87;63;62 21:59:51 INFO - PROCESS | 6214 | |9;json-stringify-tinderbox;66;65;65;65;66;66;65;66;65;65 21:59:51 INFO - PROCESS | 6214 | |10;stanford-crypto-aes;79;80;79;80;83;81;82;81;83;81 21:59:51 INFO - PROCESS | 6214 | |11;stanford-crypto-ccm;144;135;134;147;133;137;134;134;174;141 21:59:51 INFO - PROCESS | 6214 | |12;stanford-crypto-pbkdf2;186;172;187;174;172;179;174;170;178;170 21:59:51 INFO - PROCESS | 6214 | |13;stanford-crypto-sha256-iterative;77;74;72;72;72;72;71;73;73;72 21:59:51 INFO - PROCESS | 6214 | __end_tp_report 21:59:51 INFO - PROCESS | 6214 | __start_cc_report 21:59:51 INFO - PROCESS | 6214 | _x_x_mozilla_cycle_collect,936 21:59:51 INFO - PROCESS | 6214 | __end_cc_report 21:59:51 INFO - PROCESS | 6214 | __startTimestamp1461128391115__endTimestamp 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | ------- Summary: start ------- 21:59:51 INFO - PROCESS | 6214 | Number of tests: 14 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#0] ai-astar Cycles:10 Average:114.70 Median:114.00 stddev:4.97 (4.4%) stddev-sans-first:5.26 21:59:51 INFO - PROCESS | 6214 | Values: 114.0 108.0 114.0 112.0 113.0 124.0 112.0 113.0 123.0 114.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#1] audio-beat-detection Cycles:10 Average:157.90 Median:166.00 stddev:22.27 (13.4%) stddev-sans-first:23.18 21:59:51 INFO - PROCESS | 6214 | Values: 170.0 132.0 163.0 192.0 132.0 169.0 156.0 146.0 187.0 132.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#2] audio-dft Cycles:10 Average:165.80 Median:165.00 stddev:4.08 (2.5%) stddev-sans-first:3.06 21:59:51 INFO - PROCESS | 6214 | Values: 174.0 164.0 167.0 172.0 163.0 163.0 163.0 165.0 165.0 162.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#3] audio-fft Cycles:10 Average:92.40 Median:74.00 stddev:37.99 (51.3%) stddev-sans-first:39.71 21:59:51 INFO - PROCESS | 6214 | Values: 74.0 74.0 74.0 173.0 75.0 77.0 73.0 75.0 155.0 74.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#4] audio-oscillator Cycles:10 Average:108.70 Median:94.00 stddev:23.51 (25.0%) stddev-sans-first:24.24 21:59:51 INFO - PROCESS | 6214 | Values: 93.0 94.0 140.0 94.0 94.0 95.0 147.0 95.0 94.0 141.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 21:59:51 INFO - PROCESS | 6214 | Values: 118.0 119.0 117.0 119.0 117.0 118.0 118.0 118.0 118.0 117.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 21:59:51 INFO - PROCESS | 6214 | Values: 147.0 148.0 147.0 148.0 147.0 148.0 146.0 146.0 147.0 148.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:95.50 stddev:1.52 (1.6%) stddev-sans-first:1.58 21:59:51 INFO - PROCESS | 6214 | Values: 95.0 96.0 95.0 100.0 95.0 96.0 96.0 95.0 96.0 95.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:64.00 stddev:7.50 (11.7%) stddev-sans-first:7.88 21:59:51 INFO - PROCESS | 6214 | Values: 64.0 65.0 64.0 64.0 64.0 71.0 64.0 87.0 63.0 62.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 21:59:51 INFO - PROCESS | 6214 | Values: 66.0 65.0 65.0 65.0 66.0 66.0 65.0 66.0 65.0 65.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.45 (1.8%) stddev-sans-first:1.36 21:59:51 INFO - PROCESS | 6214 | Values: 79.0 80.0 79.0 80.0 83.0 81.0 82.0 81.0 83.0 81.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:139.00 stddev:12.45 (9.0%) stddev-sans-first:13.17 21:59:51 INFO - PROCESS | 6214 | Values: 144.0 135.0 134.0 147.0 133.0 137.0 134.0 134.0 174.0 141.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:176.00 stddev:6.20 (3.5%) stddev-sans-first:5.46 21:59:51 INFO - PROCESS | 6214 | Values: 186.0 172.0 187.0 174.0 172.0 179.0 174.0 170.0 178.0 170.0 21:59:51 INFO - PROCESS | 6214 | 21:59:51 INFO - PROCESS | 6214 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:72.50 stddev:1.69 (2.3%) stddev-sans-first:0.87 21:59:51 INFO - PROCESS | 6214 | Values: 77.0 74.0 72.0 72.0 72.0 72.0 71.0 73.0 73.0 72.0 21:59:51 INFO - PROCESS | 6214 | -------- Summary: end -------- 21:59:51 INFO - PROCESS | 6214 | 21:59:52 INFO - TEST-INFO | 6214: exit 0 21:59:52 INFO - TEST-OK | kraken | took 63727ms 21:59:52 INFO - SUITE-END | took 1178s 21:59:52 INFO - Completed test suite (00:19:39) 21:59:52 INFO - javascript benchmark 21:59:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2610.5953577195532, 2676.330917270682, 2707.6344693466376, 2716.551768958122, 2718.7417195366447, 4642.094791515651, 4682.789274482648, 4705.788239529221, 4719.362886010387, 4726.85819425417, 4574.565416285456, 4685.25035994241, 4735.218688916325, 4750.7149571025675, 4794.352762377078, 4326.805293761772, 4418.160549495602, 4418.226810308183, 4418.82324707013, 4423.0711550574415, 582.2860072380016, 4268.125034367963, 4333.2416827055295, 4403.141387429452, 4443.533428989958, 4350.543192964725, 4383.101464199864, 4394.868153955386, 4409.933850992237, 4444.844430444951, 4210.5157906840495, 4451.5103338632725, 4462.977685111586, 4475.731456112644, 4519.028408892071, 4300.118475712471, 4331.090471001094, 4331.501877284121, 4332.913341733148, 4395.714278571914, 4582.633389328846, 4628.190066738307, 4635.072985402933, 4641.071785642885, 4705.082508910764, 4967.379077615298, 4984.152694042022, 5006.224035274538, 5014.824481143142, 5067.239914012891, 5117.360329958755, 5120.564751996085, 5126.5898728101665, 5127.948720512784, 5135.512126347991, 4752.548507891814, 4778.737169455713, 4783.258594917794, 4789.928151077736, 4823.686460380064, 4961.578265847444, 5153.510416510426, 5250.291210686509, 5262.342207224097, 5267.552258058031, 4937.901241975141, 4954.752262386866, 4956.107900577858, 4968.975155124273, 5025.095482813129, 5062.0382127396415, 5063.0, 5065.265536497261, 5069.062223488666, 5089.440161582223, 4834.299026641117, 5030.8239211627415, 5061.0, 5101.158308878993, 5120.411152717411, 4805.207140821795, 4930.531599498042, 4973.2540118981715, 4974.5274198951765, 5030.597552195815, 4250.936235956463, 4333.458317710286, 4341.305391137403, 4375.212461756914, 4382.035952090535, 3647.7264205184715, 3685.686716629063, 3744.756590821588, 3765.849366025383, 3785.545734511876, 4417.911641767211, 4427.734335939854, 4434.800433980478, 4450.755208463565, 4468.32975053738, 4932.605391568666, 5102.0, 5128.8717782055, 5134.204198349262, 5142.382914050338, 4343.67422443318, 4389.561043895585, 4395.120975804788, 4428.734275943454, 4442.111577684476, 4473.619742321874, 4474.418325617649, 4482.21561226784, 4523.592876641118, 4537.001859590885, 4695.084458530622, 4704.811807527661, 4707.693999889996, 4719.10337035962, 4871.585915197106, 5012.223105418666, 5047.697138171721, 5050.747462626928, 5052.368453943257, 5103.413107492686, 4756.7145971241835, 4766.428028636586, 4768.809247630056, 4774.30772537987, 4829.420469543677, 4821.807127714852, 4983.07813054586, 5056.6713163644245, 5073.239014147907, 5088.135017047037, 5066.620003499678, 5089.211172268304, 5117.641765076409, 5127.230915362726, 5182.222666600041, 4929.211326187793, 4953.356063711694, 4954.529319714621, 4956.529129732669, 4964.982178653439, 4759.6668233223345, 4924.72913989734, 4971.6022718183185, 4988.226824842155, 5037.899242015066, 4635.420572428447, 4856.955754512655, 4890.535399137077, 4899.289603007473, 4955.776990035529, 5006.849794506171, 5036.445990940921, 5049.2426136079885, 5057.873553161053, 5084.644074914869, 4702.694324868872, 4877.926831097466, 4911.3369695090705, 4945.530174633404, 4967.975160124176], "value": 4628.071585782642, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.5865893874286, 608.2366629879502, 624.5347216323838, 639.827246643406, 650.304174533249, 35538.466922996144, 36608.0, 36692.44961325582, 36739.26521469569, 36987.0, 35576.0, 36128.37422315995, 36335.81832090842, 36423.817880910625, 36450.0, 7567.129780075293, 7798.961005194995, 7817.374610031184, 7824.452288339819, 7828.647710853025, 7557.0, 7616.885746713804, 7678.193789652089, 7687.115981662097, 7691.269329413724, 7647.082350117966, 7703.46075774696, 7708.498947568418, 7711.961440192791, 7714.807129821771, 7605.505642133244, 7615.124260710034, 7637.961810190969, 7646.732364367221, 7664.885026724603, 7675.117361503443, 7775.650095745705, 7777.766666999943, 7787.143414224431, 7821.061472623264, 29296.267593310124, 34948.0, 35342.46986295208, 35374.11564710903, 35593.466098008554, 2878.4962631539606, 3063.9080827574985, 3370.7977521348553, 3375.8480868360984, 3461.705755010827, 27045.188644340702, 27229.0, 27305.863470682718, 27499.45001099987, 27665.170044898685, 3328.1014126185796, 3365.3101114271763, 3415.9487607685905, 3476.0, 3524.400851855191, 225298.0, 232051.0, 232079.0, 232968.0, 233237.83381083157, 56424.15363769547, 57395.42604573984, 57454.0, 57550.13674794881, 57560.42439575551, 68457.31542684509, 70015.0, 70331.29668703247, 70845.0, 70921.29078709146, 14075.507357242448, 14266.929980251523, 14280.214588197749, 14306.427742890168, 14318.355673994694, 10464.11055060313, 10549.103326217204, 10560.102391296672, 10560.841587376195, 10587.947060264803, 19484.415467535997, 19693.803061969196, 19786.307479238156, 19799.505012374804, 19920.3027894023, 7283.854322913512, 7492.438067144986, 7637.541747495168, 7659.0981771666175, 7769.223077692299, 910.977225569366, 915.8122584870085, 922.3359181389388, 1021.1728499915092, 1051.1275641217771, 27646.170614881587, 28148.577771333446, 28863.567046494318, 28913.277168070967, 29097.127086187447, 1004.3070281505737, 1005.7686732051669, 1034.4310629153936, 1090.2150451674781, 1115.893990070942, 14710.485133020293, 15243.542693719206, 15290.617734556725, 15457.84542154564, 15515.612109697347, 320855.0, 325452.0, 327312.0, 328604.0, 329248.0, 47757.0, 48244.758776206596, 48267.0, 48523.272150917765, 48645.75677121663, 1195.5636192789568, 1248.431963456625, 1249.8375211222665, 1252.4864805429913, 1265.3040827544814, 26797.0, 27682.615869206056, 27793.58309625317, 28224.15327540177, 28269.57595636108, 41946.0, 42402.363964541175, 42578.36132458078, 42824.92937676583, 42829.78585107055, 299056.0, 300029.0, 300231.0, 301182.0, 301399.0, 25897.870510647324, 26187.214383568524, 26259.737402625728, 26426.339341516617, 26485.33786655349, 42245.1550968985, 42516.787416062725, 42956.14087718291, 43188.568114319714, 43208.135837283706, 35464.46803297901, 35931.64068359283, 36613.81693091518, 36685.0, 36697.44953825748, 35483.82258088693, 36095.639043609226, 36490.0, 36687.63312366842, 36879.8156009229, 35710.28579428345, 35737.0, 36532.63467365292, 37791.0, 37845.43231851469, 27394.5890811642, 29506.852465737535, 29915.700842991293, 31789.523157152198, 32232.516512251867, 24887.502249954538, 25002.0, 25103.246902592953, 25255.242342729747, 25491.0], "value": 17537.8310811699, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.6278809554731, 386.15432203474387, 389.4139320322914, 400.4553806822722, 404.1895998522961, 13116.147450415716, 14888.77666834997, 15990.600234994132, 16586.834131658663, 16599.834001659994, 15725.60685982851, 16029.278682459288, 16297.0, 16868.325266989305, 16916.577085572837, 4955.801767929279, 4956.776945037482, 5133.255677926699, 5138.460461651529, 5155.072087024334, 5206.140986737184, 5206.427292997767, 5224.059669259532, 5230.450802665722, 5234.031704134728, 5217.765200565965, 5410.134378499441, 6085.604435711664, 6087.78692745754, 6096.725647345858, 5637.295338082739, 6014.729337179838, 6021.578489505737, 6024.246969128859, 6051.243594550681, 5394.595405344615, 5411.161270003116, 5432.049391356536, 5436.565074794007, 5443.020256353855, 12016.339101349422, 12210.32843193624, 13347.532836350778, 20016.499587510283, 20077.196912123498, 1810.6740786658397, 1944.5819148882917, 1962.8822270663807, 2185.9344219673276, 2294.66727324537, 8999.730008099768, 9097.545122743837, 9145.45127292358, 9420.199283060949, 9515.381500202464, 1744.799348074975, 1774.8225177482277, 1825.771778527684, 1840.788309344429, 1844.732513785507, 65932.0, 66182.66908665426, 67462.3253767461, 67905.3209467904, 67930.66034669794, 9846.901530984598, 9848.2613803965, 9849.606015759433, 9919.80160396788, 9958.253131015057, 11858.0, 12688.0, 13598.456061757419, 13637.181769093886, 13676.521321753691, 6790.660466976631, 6865.862682746317, 6884.449244060464, 6991.93008069923, 7010.964945175344, 6442.549021568539, 6467.579607325603, 6516.0877477153235, 6538.051982462516, 6543.476521878239, 4138.751674899516, 4192.685548583869, 4259.446271984684, 4265.296226122655, 4344.565543445693, 1795.2908601102492, 1811.664842004207, 1816.8637352198639, 1821.7176337667684, 1862.990685046566, 883.1345281624044, 887.4231749362966, 939.2439086535268, 968.4237878462303, 1046.5499835070993, 14442.700156985922, 16138.273777680033, 16220.59448513773, 16327.0, 16503.752443713354, 869.9652013919373, 913.3789023463976, 915.1077699243318, 916.9449833010041, 923.7967647117623, 8968.955155224183, 9245.8938489411, 9284.907150928539, 9380.95309523448, 9509.429434233829, 369154.0, 370874.0, 370973.0, 374949.0, 379371.0, 46625.766871165426, 54183.45816541784, 67765.66117169481, 73663.63168184125, 75652.62173689206, 928.7538802217417, 949.1220620925616, 957.9856302155334, 961.4087336288272, 963.0754475703403, 14258.71482570322, 14362.640933976734, 14624.415023398944, 14630.178548259912, 14933.55199343978, 25674.743252567234, 31676.0, 31930.521042183922, 32858.17854553559, 35573.644263557035, 41587.7920610395, 41739.791301043304, 41875.7906210467, 42759.0, 43167.56832431635, 7760.3403710685225, 7891.408144389308, 7928.445008849325, 7938.563379013978, 7996.480228785111, 11101.833472498081, 11372.488238029475, 11603.709907252387, 11614.88385116138, 11821.70445738829, 9408.717738467856, 9553.761155971157, 9914.752131196488, 10128.74678133023, 10220.540075696463, 9760.072793084646, 9841.704748857546, 10174.389536627827, 10307.845382319421, 10399.428031458343, 5060.696358218519, 9997.000299969945, 10269.948650256702, 10304.845427318447, 10390.896091038992, 7551.546907185587, 7571.0, 7582.355499782359, 7611.4291428144215, 7739.18738532446, 7831.686732530635, 9943.502824858873, 10203.846942295722, 10211.489425528842, 10219.7445063874], "value": 7956.599404218922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.4804644351252, 1495.5961890289623, 1634.403442743399, 1759.5689056181238, 1824.7171688388296, 6957.860842783141, 7666.961665191666, 7733.149353571102, 7991.280784729373, 8023.598820058995, 7622.733204337849, 7785.455018148732, 7788.766337009884, 7806.609669516516, 7877.291043806056, 2253.4591697992446, 2284.097781376355, 2295.4605667668084, 2312.583734927712, 2315.5253173099472, 2201.3395981205654, 2204.0522575292616, 2262.7963483286503, 2269.614165591845, 2275.0444813178424, 2252.4706693926914, 2283.737370202423, 2285.314405678298, 2302.7697230277004, 2308.3651995701234, 2231.620624493824, 2257.4243567890167, 2261.378121016717, 2264.5923733727923, 2271.4775601611555, 2242.316093591454, 2257.401788526042, 2259.45773014477, 2261.9095236190697, 2286.634138537843, 8024.719134830311, 8086.595670216466, 8089.555074470902, 8104.027516697975, 8143.144969778147, 4694.600958918496, 4838.879028024292, 4843.4187897452175, 4846.030793841246, 4860.951390486085, 2846.501862174132, 2909.3453972855937, 2927.0926012935847, 2940.1767505098605, 2946.5727469517014, 1601.9038857668577, 1623.2938671677857, 1629.291258302642, 1639.9918000410041, 1695.8643308535288, 10499.160067194682, 10758.354498730101, 10787.298825576312, 10789.460526973699, 10821.513031913582, 4307.741535507879, 4389.429374181336, 4438.844640437569, 4444.533324000998, 4449.577290157495, 8476.406651534457, 8504.489730616058, 8524.14758524155, 8532.53071081084, 8534.0, 4718.41019872516, 4732.195526760459, 4746.95253047472, 4762.499937506581, 4775.808967641325, 3778.6032466591164, 4266.445362102907, 4399.49405818335, 4411.669124815651, 4449.844255451108, 3592.76647017943, 3646.6171052039685, 3722.5346831646043, 3737.5141231639714, 3754.136548593784, 1121.9270747401392, 1137.624583887315, 1140.5836869542557, 1155.4742592120565, 1164.7088227943013, 791.4815795653856, 906.8549032154824, 911.9270458363314, 922.5202894494826, 935.9953200233955, 5249.50129737682, 5303.310569625924, 5358.33020872391, 5492.80775172875, 5554.91667624986, 413.98965025874594, 422.5014482910195, 429.5790125676854, 429.90112274177113, 432.36010704158025, 3734.8879533614036, 3921.3333733265413, 3950.20995800841, 3965.88102356924, 3969.0, 8939.329550283624, 9264.351495395258, 9295.163435290857, 9296.0, 9327.766805829773, 9067.591958361889, 9083.863742043875, 9256.907430925605, 9670.75823104428, 9691.0, 7230.240824713434, 7380.225076367012, 7422.109346878409, 7435.628218589049, 7437.702491900263, 5656.406077361818, 5793.884122317614, 5900.203472531153, 5909.793157239476, 6012.068129439944, 9122.0, 9180.311476639145, 9194.540272986458, 9222.538873056454, 9247.953760231156, 8676.91323086761, 8869.73390798277, 8880.245179159585, 8948.821023579361, 9019.774505637411, 6106.297775755845, 6594.142761440982, 6763.93236067633, 6779.423748981392, 7021.613811240431, 7259.854802903807, 7261.237570055279, 7311.086114235721, 7320.743773967885, 7323.487355885037, 6506.512011599054, 6664.600123992576, 6698.966505167443, 6712.2952090031795, 6830.556013859084, 6564.4748420125325, 6650.035744816878, 6666.899996500153, 6698.39714425704, 6750.2237242715755, 6332.0818481320875, 6382.6170429774365, 6419.390157934989, 6434.742610295723, 6467.741290348334, 4758.119747846659, 4790.377250957354, 4887.828925987716, 4916.385451818523, 4927.039227350632, 4517.209488339593, 4624.953750462587, 4728.668993170445, 4733.9763301183275, 4743.264793956942], "value": 4206.088486015627, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.3663694381933, 321.79565975605493, 323.0518428412608, 324.3464419195321, 330.9420851351014, 5035.798568057283, 5163.948360516394, 5217.9739101304485, 5294.92057619136, 5311.62818602698, 5223.791048358071, 5261.86845328867, 5262.526372626463, 5263.973680131604, 5281.419043905167, 4114.732542384751, 4117.353058776491, 4123.628873401393, 4126.8968275793195, 4135.710500264982, 4066.755994640331, 4074.7147699661036, 4116.629503344699, 4134.338505839067, 4146.3158578834455, 4054.5738850671282, 4075.470188875442, 4099.6720262379085, 4102.3026085565525, 4102.733322334038, 4090.365993271048, 4092.263392589318, 4104.1381309924955, 4105.261053010457, 4117.5882411758585, 3633.5639723233116, 3636.090977255686, 3644.5990940996735, 3649.2154186849953, 3661.029827095822, 4955.256711493269, 4985.202367621163, 5052.317937078484, 5055.34280543527, 5063.594912407036, 4641.466231383401, 4685.648576356787, 4698.154332220199, 4712.670113092085, 4722.858314250544, 5493.901219756065, 5514.724263786835, 5515.227868098469, 5527.2538207341895, 5530.11518157097, 3823.5220597425323, 3954.130091379892, 3958.9208215835524, 3961.861334853295, 3979.5622481527007, 5753.654780713129, 5760.567957403212, 5783.913241301384, 5803.941960580382, 5823.165702803603, 4069.064115253508, 4073.165001174752, 4090.3250963590676, 4090.8159132839096, 4096.078382363944, 4028.436018957348, 4141.358089496133, 4149.522804877417, 4151.543330233671, 4155.97922010388, 4678.719276843401, 4679.344891715163, 4692.9530704692515, 4694.225452800318, 4708.317293992352, 5183.429822719498, 5486.945130548724, 5547.278853748987, 5548.528375088082, 5572.3870374258795, 1388.0145096981257, 1426.472205284031, 1430.4135304525096, 1435.9928200358931, 1446.0, 973.2992245583168, 976.1556253840376, 998.9600415983425, 1010.7119470950744, 1013.1793247469426, 4751.40607424072, 4767.356406885026, 4787.3776409067295, 4791.305260737243, 4793.209120495149, 5630.634008789498, 5649.406812284733, 5661.34894487131, 5669.716514174274, 5671.234383358276, 4649.442066951988, 4779.426468823764, 4781.808727650855, 4797.376341075638, 4799.808007679723, 3002.879884804627, 3010.5935698680837, 3015.6079709637606, 3043.6347638283546, 3051.5575241589845, 5310.707911064928, 5334.333208348957, 5340.706261155596, 5343.118385466432, 5350.946490535122, 5597.272354593877, 5758.596898217168, 5759.568032397503, 5784.59507834456, 5785.132230165509, 5318.97340513295, 5322.41453440129, 5328.573714102862, 5346.037713211582, 5355.598330125256, 5397.271368365377, 5599.776008959759, 5620.831375058755, 5643.181738647955, 5646.717664116695, 5499.642523235977, 5501.5323697486165, 5532.336119665505, 5545.750441230235, 5552.278203833475, 5289.788408463773, 5295.841124766263, 5312.150055991023, 5325.041492531382, 5333.626646134733, 3769.4722738817136, 4061.7156799024965, 4066.512018557792, 4086.6117718816663, 4087.3051581231857, 5469.890602187855, 5609.27079479665, 5709.286339207599, 5741.224934633938, 5741.3971532988435, 5573.247611572548, 5607.691576963143, 5609.411011843697, 5623.775048997994, 5648.491635752802, 5494.230807686847, 5569.637973531708, 5593.188987596857, 5596.608237423341, 5597.3003374578175, 5172.5344718975475, 5435.673859568438, 5483.61614686984, 5484.561235101262, 5484.7806087757635, 5596.244506991667, 5640.351359593699, 5649.237352957298, 5662.745176466987, 5668.971655141698, 5199.76601052946, 5483.7258137092185, 5518.530924871431, 5530.364008139116, 5545.445455454423], "value": 4166.183100577973, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.5795985468939, 687.7730348984835, 703.8240439890028, 712.2094475132602, 717.7810767715845, 10872.303456653672, 10969.451527423627, 11063.446827658625, 11163.944180279108, 11200.943995280022, 10919.344839309628, 11019.118470522359, 11129.499172537235, 11146.777064458705, 11358.772824543505, 644.1915396177814, 790.9090454597738, 802.5545822068749, 818.4557269415832, 821.0393839208111, 812.3257696112242, 812.585581353511, 812.6911773526082, 812.9227723366271, 813.7436707437139, 840.5965136734371, 841.9536925469097, 845.049319515545, 845.2772879188298, 846.6232526525698, 836.4855613797531, 838.6393850644221, 838.8532006898829, 840.4789030800912, 840.8528507511161, 606.65723866016, 614.1862032806549, 616.1743264026181, 617.7096764520668, 618.1068356225211, 11446.370449625267, 11462.0, 11509.136814738928, 11528.135389845711, 11562.595309164222, 12185.756284874253, 12560.246385216917, 12664.746705065847, 13039.826415622545, 13118.278494682789, 11298.322100673986, 11386.202965792398, 11409.14431417647, 11469.426528673534, 11526.62510423699, 4066.105456799529, 4079.5104587449696, 4151.46031015969, 4164.1879833432495, 4187.979060104711, 47083.0, 47403.76298118522, 48337.51662483365, 49237.0, 49352.75323623395, 10119.848202276971, 10277.537510812032, 10328.793424131476, 10400.47997600117, 10439.686809395731, 16919.0, 18599.07004649762, 18600.0, 18625.789323693916, 18639.627207456047, 6066.575339726177, 6095.391835941509, 6127.785527506605, 6129.233845769279, 6210.472109870621, 3264.3634491273974, 3413.74396920232, 3453.2920751245933, 3461.048211741791, 3514.7715398499017, 2719.1706529508688, 2807.9157625271273, 2812.0860720265996, 2828.179827849942, 2837.929051773681, 502.6682389622831, 503.2099603622278, 506.6099103690138, 513.1456125550991, 514.578046002282, 527.6385675812081, 577.1861675038176, 579.8666306749471, 579.9971000144973, 599.4485073732177, 7349.853002940018, 7362.779116626402, 7554.811129721691, 7763.650635721406, 7790.610469476617, 496.61016102360196, 545.6371512943928, 586.357938057826, 597.6444015810586, 603.6257520337331, 3585.1216451969435, 3631.673149416565, 3670.6145854685406, 3696.759710618855, 3732.42147467143, 49944.750276248385, 52132.47867521352, 52241.47758522442, 52293.73853130786, 52462.213066804026, 43713.78143109264, 45150.32274515885, 45346.093078138256, 45661.77169114133, 46509.0, 42156.367654485206, 42347.78826105911, 42619.147617047485, 42929.57070429256, 43163.784181078896, 6997.370236678723, 7164.104486939133, 7180.60506672138, 7183.102112235971, 7316.195218526064, 28168.71831281661, 28295.717042829307, 28537.714622853506, 29111.272218193866, 29341.0, 35587.82206088953, 37327.25345493021, 37347.0, 37503.624963750015, 37655.62344376521, 9367.250619950524, 9388.57751401175, 9483.905160948303, 9541.618335266789, 9697.854532181882, 10096.394216347042, 10268.754891180508, 10413.583456661952, 10473.423961682181, 10688.25182237236, 8691.608877600396, 8754.956225218833, 8827.514486703292, 9010.27917766564, 9098.317626178195, 8274.793130171794, 8336.124706905688, 8347.290480309242, 8381.49711017341, 8431.662733490837, 9129.0, 9668.809778941893, 10709.678709638723, 10971.890281097087, 11059.115270778157, 9150.542472876463, 9381.812363752822, 9482.620695172393, 9647.517624118625, 9758.170555502862, 8539.658413663385, 8821.382503224713, 9010.909890901006, 9110.180083792491, 9115.680951166678], "value": 5728.972296486252, "unit": "score"}], "name": "dromaeo_css", "value": 6338.313473255193, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 114.0, 112.0, 113.0, 124.0, 112.0, 113.0, 123.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 132.0, 163.0, 192.0, 132.0, 169.0, 156.0, 146.0, 187.0, 132.0], "value": 157.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 164.0, 167.0, 172.0, 163.0, 163.0, 163.0, 165.0, 165.0, 162.0], "value": 165.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 173.0, 75.0, 77.0, 73.0, 75.0, 155.0, 74.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 140.0, 94.0, 94.0, 95.0, 147.0, 95.0, 94.0, 141.0], "value": 108.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 117.0, 119.0, 117.0, 118.0, 118.0, 118.0, 118.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 146.0, 146.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 100.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 71.0, 64.0, 87.0, 63.0, 62.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 80.0, 83.0, 81.0, 82.0, 81.0, 83.0, 81.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 135.0, 134.0, 147.0, 133.0, 137.0, 134.0, 134.0, 174.0, 141.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 172.0, 187.0, 174.0, 172.0, 179.0, 174.0, 170.0, 178.0, 170.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 72.0, 72.0, 72.0, 72.0, 71.0, 73.0, 73.0, 72.0], "value": 72.8, "unit": "score"}], "name": "kraken", "value": 1603.9000000000003, "alertThreshold": 2.0}]} 21:59:52 INFO - Return code: 0 21:59:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:59:52 ERROR - # TBPL SUCCESS # 21:59:52 INFO - Running post-action listener: _resource_record_post_action 21:59:52 INFO - Running post-run listener: _resource_record_post_run 21:59:53 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 517627904; Read time: 12; Write time: 448076 21:59:53 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 233701376; Read time: 0; Write time: 250468 21:59:53 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283328512; Read time: 12; Write time: 197608 21:59:53 INFO - Running post-run listener: _upload_blobber_files 21:59:53 INFO - Blob upload gear active. 21:59:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:59:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:59:53 INFO - (blobuploader) - INFO - Open directory for files ... 21:59:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:59:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:59:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:54 INFO - (blobuploader) - INFO - Done attempting. 21:59:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:59:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:59:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:55 INFO - (blobuploader) - INFO - Done attempting. 21:59:55 INFO - (blobuploader) - INFO - Iteration through files over. 21:59:55 INFO - Return code: 0 21:59:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:59:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:59:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3e670f04d851b48b6ca6d767852a8cad2303a9865fd134b7f30ce7a15723e6d313d262f0a2f6ce6565d51581ae8dfaa78b030aeb93f2b5b7170e37a03f88cd7e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c17f26b85a0a3bec8e9f9d14c79219ce0ec6d777464c7a91134a4e01588f575b18ae846caa2880912fc193ef1cc9646910aa07acd960d0217e61bd67d8e87ad"} 21:59:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:59:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:59:55 INFO - Contents: 21:59:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3e670f04d851b48b6ca6d767852a8cad2303a9865fd134b7f30ce7a15723e6d313d262f0a2f6ce6565d51581ae8dfaa78b030aeb93f2b5b7170e37a03f88cd7e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c17f26b85a0a3bec8e9f9d14c79219ce0ec6d777464c7a91134a4e01588f575b18ae846caa2880912fc193ef1cc9646910aa07acd960d0217e61bd67d8e87ad"} 21:59:55 INFO - Running post-run listener: copy_logs_to_upload_dir 21:59:55 INFO - Copying logs to upload dir... 21:59:55 INFO - mkdir: /builds/slave/test/build/upload/logs 21:59:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.737759 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-19 21:59:55.070240) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 21:59:55.075518) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710 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/Fx-Team-Non-PGO/sha512/3e670f04d851b48b6ca6d767852a8cad2303a9865fd134b7f30ce7a15723e6d313d262f0a2f6ce6565d51581ae8dfaa78b030aeb93f2b5b7170e37a03f88cd7e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c17f26b85a0a3bec8e9f9d14c79219ce0ec6d777464c7a91134a4e01588f575b18ae846caa2880912fc193ef1cc9646910aa07acd960d0217e61bd67d8e87ad"} build_url:https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/QZYCHcs2QUOmagDe8KrGWA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3e670f04d851b48b6ca6d767852a8cad2303a9865fd134b7f30ce7a15723e6d313d262f0a2f6ce6565d51581ae8dfaa78b030aeb93f2b5b7170e37a03f88cd7e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c17f26b85a0a3bec8e9f9d14c79219ce0ec6d777464c7a91134a4e01588f575b18ae846caa2880912fc193ef1cc9646910aa07acd960d0217e61bd67d8e87ad"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 21:59:55.100962) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:59:55.101319) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461123055.502557-694702710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 21:59:55.186004) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 21:59:55.186358) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 21:59:55.186694) ========= ========= Total master_lag: 0.18 =========