builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-039 starttime: 1465003832.99 results: success (0) buildid: 20160603160527 builduid: b7689d4d25354adb976fc889ee28d97a revision: ab782095ef4ab44dae4cc5f919858c5684837378 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:32.990223) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:32.990734) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:32.991148) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:33.072047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:33.072321) ========= 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=5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-03 18:30:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.8M=0s 2016-06-03 18:30:33 (37.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112738 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:33.199379) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:33.199731) ========= 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=5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019264 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:33.249492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 18:30:33.249839) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ab782095ef4ab44dae4cc5f919858c5684837378 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ab782095ef4ab44dae4cc5f919858c5684837378 --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=5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-03 18:30:33,809 truncating revision to first 12 chars 2016-06-03 18:30:33,809 Setting DEBUG logging. 2016-06-03 18:30:33,809 attempt 1/10 2016-06-03 18:30:33,809 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ab782095ef4a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 18:30:34,657 unpacking tar archive at: mozilla-aurora-ab782095ef4a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.182406 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 18:30:34.444951) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:34.445268) ========= script_repo_revision: ab782095ef4ab44dae4cc5f919858c5684837378 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:34.445702) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:34.445972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 18:30:34.453841) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-06-03 18:30:34.454145) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:30:35 INFO - MultiFileLogger online at 20160603 18:30:35 in /builds/slave/test 18:30:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:30:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:30:35 INFO - {'append_to_log': False, 18:30:35 INFO - 'base_work_dir': '/builds/slave/test', 18:30:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:30:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:30:35 INFO - 'branch': 'Mozilla-Aurora', 18:30:35 INFO - 'buildbot_json_path': 'buildprops.json', 18:30:35 INFO - 'config_files': ('talos/linux_config.py',), 18:30:35 INFO - 'default_actions': ('clobber', 18:30:35 INFO - 'read-buildbot-config', 18:30:35 INFO - 'download-and-extract', 18:30:35 INFO - 'populate-webroot', 18:30:35 INFO - 'create-virtualenv', 18:30:35 INFO - 'install', 18:30:35 INFO - 'run-tests'), 18:30:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:30:35 INFO - 'download_minidump_stackwalk': True, 18:30:35 INFO - 'download_symbols': 'ondemand', 18:30:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:30:35 INFO - 'tooltool.py': '/tools/tooltool.py', 18:30:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:30:35 INFO - '/tools/misc-python/virtualenv.py')}, 18:30:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:30:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:30:35 INFO - 'installer_path': 'installer.exe', 18:30:35 INFO - 'log_level': 'info', 18:30:35 INFO - 'log_name': 'talos', 18:30:35 INFO - 'log_to_console': True, 18:30:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:30:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:30:35 INFO - 'opt_config_files': (), 18:30:35 INFO - 'pip_index': False, 18:30:35 INFO - 'sps_profile': False, 18:30:35 INFO - 'sps_profile_interval': 0, 18:30:35 INFO - 'suite': 'dromaeojs', 18:30:35 INFO - 'system_bits': '32', 18:30:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:30:35 INFO - 'title': 'talos-linux64-ix-039', 18:30:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:30:35 INFO - 'use_talos_json': True, 18:30:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:30:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:30:35 INFO - 'work_dir': 'build'} 18:30:35 INFO - ##### 18:30:35 INFO - ##### Running clobber step. 18:30:35 INFO - ##### 18:30:35 INFO - Running pre-action listener: _resource_record_pre_action 18:30:35 INFO - Running main action method: clobber 18:30:35 INFO - rmtree: /builds/slave/test/build 18:30:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:30:35 INFO - Running post-action listener: _resource_record_post_action 18:30:35 INFO - ##### 18:30:35 INFO - ##### Running read-buildbot-config step. 18:30:35 INFO - ##### 18:30:35 INFO - Running pre-action listener: _resource_record_pre_action 18:30:35 INFO - Running main action method: read_buildbot_config 18:30:35 INFO - Using buildbot properties: 18:30:35 INFO - { 18:30:35 INFO - "project": "", 18:30:35 INFO - "product": "firefox", 18:30:35 INFO - "script_repo_revision": "production", 18:30:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 18:30:35 INFO - "repository": "", 18:30:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 18:30:35 INFO - "buildid": "20160603160527", 18:30:35 INFO - "pgo_build": "True", 18:30:35 INFO - "basedir": "/builds/slave/test", 18:30:35 INFO - "buildnumber": 358, 18:30:35 INFO - "slavename": "talos-linux64-ix-039", 18:30:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:30:35 INFO - "platform": "ubuntu64_hw", 18:30:35 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:30:35 INFO - "branch": "mozilla-aurora", 18:30:35 INFO - "repo_path": "releases/mozilla-aurora", 18:30:35 INFO - "revision": "ab782095ef4ab44dae4cc5f919858c5684837378", 18:30:35 INFO - "stage_platform": "linux64-pgo", 18:30:35 INFO - "builduid": "b7689d4d25354adb976fc889ee28d97a", 18:30:35 INFO - "slavebuilddir": "test" 18:30:35 INFO - } 18:30:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 18:30:35 INFO - Running post-action listener: _resource_record_post_action 18:30:35 INFO - ##### 18:30:35 INFO - ##### Running download-and-extract step. 18:30:35 INFO - ##### 18:30:35 INFO - Running pre-action listener: _resource_record_pre_action 18:30:35 INFO - Running main action method: download_and_extract 18:30:35 INFO - mkdir: /builds/slave/test/build/tests 18:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:35 INFO - https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:30:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:30:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:30:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:30:38 INFO - Downloaded 1361 bytes. 18:30:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:30:38 INFO - Using the following test package requirements: 18:30:38 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:30:38 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:30:38 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:30:38 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'jsshell-linux-x86_64.zip'], 18:30:38 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:30:38 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:30:38 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:30:38 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:30:38 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:30:38 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:30:38 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:30:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:38 INFO - https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:30:39 INFO - Downloaded 24177678 bytes. 18:30:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:30:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:30:41 INFO - Return code: 0 18:30:41 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:41 INFO - https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:30:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:30:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:30:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:30:47 INFO - Downloaded 24177678 bytes. 18:30:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:30:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:30:48 INFO - Return code: 0 18:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:48 INFO - https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:30:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:30:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:30:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:30:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:30:49 INFO - Downloaded 10840145 bytes. 18:30:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:30:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:30:49 INFO - Return code: 0 18:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:49 INFO - https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:30:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:30:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:30:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:30:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:30:51 INFO - Downloaded 62813032 bytes. 18:30:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:30:51 INFO - mkdir: /builds/slave/test/properties 18:30:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:30:51 INFO - Writing to file /builds/slave/test/properties/build_url 18:30:51 INFO - Contents: 18:30:51 INFO - build_url:https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:30:53 INFO - Running post-action listener: _resource_record_post_action 18:30:53 INFO - Running post-action listener: set_extra_try_arguments 18:30:53 INFO - ##### 18:30:53 INFO - ##### Running populate-webroot step. 18:30:53 INFO - ##### 18:30:53 INFO - Running pre-action listener: _resource_record_pre_action 18:30:53 INFO - Running main action method: populate_webroot 18:30:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:53 INFO - '--tppagecycles', 18:30:53 INFO - '7'], 18:30:53 INFO - 'tests': ['tsvgm']}}, 18:30:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:30:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:30:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:30:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['damp', 'tps']}, 18:30:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'tests': ['damp', 'tps']}, 18:30:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['dromaeo_dom']}, 18:30:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['a11yr', 18:30:53 INFO - 'ts_paint', 18:30:53 INFO - 'tpaint', 18:30:53 INFO - 'sessionrestore', 18:30:53 INFO - 'sessionrestore_no_auto_restore', 18:30:53 INFO - 'tabpaint']}, 18:30:53 INFO - 'other-e10s': {'tests': ['a11yr', 18:30:53 INFO - 'ts_paint', 18:30:53 INFO - 'tpaint', 18:30:53 INFO - 'sessionrestore', 18:30:53 INFO - 'sessionrestore_no_auto_restore', 18:30:53 INFO - 'tabpaint']}, 18:30:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:30:53 INFO - 'ts_paint', 18:30:53 INFO - 'tpaint', 18:30:53 INFO - 'sessionrestore', 18:30:53 INFO - 'sessionrestore_no_auto_restore', 18:30:53 INFO - 'tabpaint']}, 18:30:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:30:53 INFO - 'ts_paint', 18:30:53 INFO - 'tpaint', 18:30:53 INFO - 'sessionrestore', 18:30:53 INFO - 'sessionrestore_no_auto_restore', 18:30:53 INFO - 'tabpaint']}, 18:30:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['a11yr', 18:30:53 INFO - 'ts_paint', 18:30:53 INFO - 'tpaint', 18:30:53 INFO - 'sessionrestore', 18:30:53 INFO - 'sessionrestore_no_auto_restore', 18:30:53 INFO - 'tabpaint']}, 18:30:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['a11yr', 18:30:53 INFO - 'ts_paint', 18:30:53 INFO - 'tpaint', 18:30:53 INFO - 'sessionrestore', 18:30:53 INFO - 'sessionrestore_no_auto_restore', 18:30:53 INFO - 'tabpaint']}, 18:30:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['tsvgx', 18:30:53 INFO - 'tsvgr_opacity', 18:30:53 INFO - 'tart', 18:30:53 INFO - 'tscrollx', 18:30:53 INFO - 'cart']}, 18:30:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:30:53 INFO - 'tsvgr_opacity', 18:30:53 INFO - 'tart', 18:30:53 INFO - 'tscrollx', 18:30:53 INFO - 'cart']}, 18:30:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'talos_options': ['--disable-e10s'], 18:30:53 INFO - 'tests': ['tp5o']}, 18:30:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'tests': ['tp5o']}, 18:30:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'talos_options': ['--disable-e10s', 18:30:53 INFO - '--xperf_path', 18:30:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:53 INFO - 'tests': ['tp5n']}, 18:30:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:53 INFO - 'talos_options': ['--xperf_path', 18:30:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:53 INFO - 'tests': ['tp5n']}}, 18:30:53 INFO - 'talos.zip': {'path': '', 18:30:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:53 INFO - Running post-action listener: _resource_record_post_action 18:30:53 INFO - ##### 18:30:53 INFO - ##### Running create-virtualenv step. 18:30:53 INFO - ##### 18:30:53 INFO - Running pre-action listener: _resource_record_pre_action 18:30:53 INFO - Running main action method: create_virtualenv 18:30:53 INFO - Creating virtualenv /builds/slave/test/build/venv 18:30:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:30:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:30:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:53 INFO - Using real prefix '/usr' 18:30:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:30:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:55 INFO - Installing pip.................done. 18:30:55 INFO - Return code: 0 18:30:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:55 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:30:55 INFO - Using env: {'DISPLAY': ':0', 18:30:55 INFO - 'HOME': '/home/cltbld', 18:30:55 INFO - 'LANG': 'en_US.UTF-8', 18:30:55 INFO - 'LANGUAGE': 'en_US:en', 18:30:55 INFO - 'LOGNAME': 'cltbld', 18:30:55 INFO - 'MAIL': '/var/mail/cltbld', 18:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:55 INFO - 'MOZ_NO_REMOTE': '1', 18:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:55 INFO - 'NO_EM_RESTART': '1', 18:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:55 INFO - 'PWD': '/builds/slave/test', 18:30:55 INFO - 'SHELL': '/bin/bash', 18:30:55 INFO - 'SHLVL': '1', 18:30:55 INFO - 'TERM': 'linux', 18:30:55 INFO - 'TMOUT': '86400', 18:30:55 INFO - 'USER': 'cltbld', 18:30:55 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:55 INFO - '_': '/tools/buildbot/bin/python'} 18:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:30:55 INFO - Cleaning up... 18:30:55 INFO - Return code: 0 18:30:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:55 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:30:55 INFO - Using env: {'DISPLAY': ':0', 18:30:55 INFO - 'HOME': '/home/cltbld', 18:30:55 INFO - 'LANG': 'en_US.UTF-8', 18:30:55 INFO - 'LANGUAGE': 'en_US:en', 18:30:55 INFO - 'LOGNAME': 'cltbld', 18:30:55 INFO - 'MAIL': '/var/mail/cltbld', 18:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:55 INFO - 'MOZ_NO_REMOTE': '1', 18:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:55 INFO - 'NO_EM_RESTART': '1', 18:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:55 INFO - 'PWD': '/builds/slave/test', 18:30:55 INFO - 'SHELL': '/bin/bash', 18:30:55 INFO - 'SHLVL': '1', 18:30:55 INFO - 'TERM': 'linux', 18:30:55 INFO - 'TMOUT': '86400', 18:30:55 INFO - 'USER': 'cltbld', 18:30:55 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:55 INFO - '_': '/tools/buildbot/bin/python'} 18:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:56 INFO - Downloading/unpacking psutil>=0.7.1 18:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:58 INFO - Installing collected packages: psutil 18:30:58 INFO - Running setup.py install for psutil 18:30:58 INFO - building 'psutil._psutil_linux' extension 18:30:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:30:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:30:58 INFO - building 'psutil._psutil_posix' extension 18:30:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:30:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:30:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:58 INFO - Successfully installed psutil 18:30:58 INFO - Cleaning up... 18:30:58 INFO - Return code: 0 18:30:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:58 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:30:58 INFO - Using env: {'DISPLAY': ':0', 18:30:58 INFO - 'HOME': '/home/cltbld', 18:30:58 INFO - 'LANG': 'en_US.UTF-8', 18:30:58 INFO - 'LANGUAGE': 'en_US:en', 18:30:58 INFO - 'LOGNAME': 'cltbld', 18:30:58 INFO - 'MAIL': '/var/mail/cltbld', 18:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:58 INFO - 'MOZ_NO_REMOTE': '1', 18:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:58 INFO - 'NO_EM_RESTART': '1', 18:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:58 INFO - 'PWD': '/builds/slave/test', 18:30:58 INFO - 'SHELL': '/bin/bash', 18:30:58 INFO - 'SHLVL': '1', 18:30:58 INFO - 'TERM': 'linux', 18:30:58 INFO - 'TMOUT': '86400', 18:30:58 INFO - 'USER': 'cltbld', 18:30:58 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:58 INFO - '_': '/tools/buildbot/bin/python'} 18:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:31:00 INFO - Installing collected packages: mozsystemmonitor 18:31:00 INFO - Running setup.py install for mozsystemmonitor 18:31:00 INFO - Successfully installed mozsystemmonitor 18:31:00 INFO - Cleaning up... 18:31:00 INFO - Return code: 0 18:31:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:00 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:31:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:31:00 INFO - Using env: {'DISPLAY': ':0', 18:31:00 INFO - 'HOME': '/home/cltbld', 18:31:00 INFO - 'LANG': 'en_US.UTF-8', 18:31:00 INFO - 'LANGUAGE': 'en_US:en', 18:31:00 INFO - 'LOGNAME': 'cltbld', 18:31:00 INFO - 'MAIL': '/var/mail/cltbld', 18:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:00 INFO - 'MOZ_NO_REMOTE': '1', 18:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:00 INFO - 'NO_EM_RESTART': '1', 18:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:00 INFO - 'PWD': '/builds/slave/test', 18:31:00 INFO - 'SHELL': '/bin/bash', 18:31:00 INFO - 'SHLVL': '1', 18:31:00 INFO - 'TERM': 'linux', 18:31:00 INFO - 'TMOUT': '86400', 18:31:00 INFO - 'USER': 'cltbld', 18:31:00 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:00 INFO - '_': '/tools/buildbot/bin/python'} 18:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:01 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:02 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:03 INFO - Downloading docopt-0.6.1.tar.gz 18:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:03 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:03 INFO - Running setup.py install for blobuploader 18:31:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:03 INFO - Running setup.py install for requests 18:31:04 INFO - Running setup.py install for docopt 18:31:04 INFO - Successfully installed blobuploader requests docopt 18:31:04 INFO - Cleaning up... 18:31:04 INFO - Return code: 0 18:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:04 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:04 INFO - Using env: {'DISPLAY': ':0', 18:31:04 INFO - 'HOME': '/home/cltbld', 18:31:04 INFO - 'LANG': 'en_US.UTF-8', 18:31:04 INFO - 'LANGUAGE': 'en_US:en', 18:31:04 INFO - 'LOGNAME': 'cltbld', 18:31:04 INFO - 'MAIL': '/var/mail/cltbld', 18:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:04 INFO - 'MOZ_NO_REMOTE': '1', 18:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:04 INFO - 'NO_EM_RESTART': '1', 18:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:04 INFO - 'PWD': '/builds/slave/test', 18:31:04 INFO - 'SHELL': '/bin/bash', 18:31:04 INFO - 'SHLVL': '1', 18:31:04 INFO - 'TERM': 'linux', 18:31:04 INFO - 'TMOUT': '86400', 18:31:04 INFO - 'USER': 'cltbld', 18:31:04 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:04 INFO - '_': '/tools/buildbot/bin/python'} 18:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:04 INFO - Running setup.py (path:/tmp/pip-ug4QfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:04 INFO - Running setup.py (path:/tmp/pip-kFBvjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:04 INFO - Running setup.py (path:/tmp/pip-6dDBX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:04 INFO - Running setup.py (path:/tmp/pip-RohSaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:04 INFO - Running setup.py (path:/tmp/pip-KGYLzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:04 INFO - Running setup.py (path:/tmp/pip-TuBD0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:05 INFO - Running setup.py (path:/tmp/pip-5d36EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:05 INFO - Running setup.py (path:/tmp/pip-jJnKXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:05 INFO - Running setup.py (path:/tmp/pip-y0h5v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:05 INFO - Running setup.py (path:/tmp/pip-bUXfFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:05 INFO - Running setup.py (path:/tmp/pip-3zvpvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:05 INFO - Running setup.py (path:/tmp/pip-SLtQfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:05 INFO - Running setup.py (path:/tmp/pip-gBFKiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:05 INFO - Running setup.py (path:/tmp/pip-h7ooHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:05 INFO - Running setup.py (path:/tmp/pip-SGD56K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:05 INFO - Running setup.py (path:/tmp/pip-N48Xpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:05 INFO - Running setup.py (path:/tmp/pip-MMPRzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:31:05 INFO - Running setup.py install for manifestparser 18:31:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:31:05 INFO - Running setup.py install for mozcrash 18:31:06 INFO - Running setup.py install for mozdebug 18:31:06 INFO - Running setup.py install for mozdevice 18:31:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Running setup.py install for mozfile 18:31:06 INFO - Running setup.py install for mozhttpd 18:31:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Running setup.py install for mozinfo 18:31:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Running setup.py install for mozInstall 18:31:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Running setup.py install for mozleak 18:31:06 INFO - Running setup.py install for mozlog 18:31:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Running setup.py install for moznetwork 18:31:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:31:06 INFO - Running setup.py install for mozprocess 18:31:07 INFO - Running setup.py install for mozprofile 18:31:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:31:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:31:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:31:07 INFO - Running setup.py install for mozrunner 18:31:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:31:07 INFO - Running setup.py install for mozscreenshot 18:31:07 INFO - Running setup.py install for moztest 18:31:07 INFO - Running setup.py install for mozversion 18:31:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:31:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:31:07 INFO - Cleaning up... 18:31:07 INFO - Return code: 0 18:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:07 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:07 INFO - Using env: {'DISPLAY': ':0', 18:31:07 INFO - 'HOME': '/home/cltbld', 18:31:07 INFO - 'LANG': 'en_US.UTF-8', 18:31:07 INFO - 'LANGUAGE': 'en_US:en', 18:31:07 INFO - 'LOGNAME': 'cltbld', 18:31:07 INFO - 'MAIL': '/var/mail/cltbld', 18:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:07 INFO - 'MOZ_NO_REMOTE': '1', 18:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:07 INFO - 'NO_EM_RESTART': '1', 18:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:07 INFO - 'PWD': '/builds/slave/test', 18:31:07 INFO - 'SHELL': '/bin/bash', 18:31:07 INFO - 'SHLVL': '1', 18:31:07 INFO - 'TERM': 'linux', 18:31:07 INFO - 'TMOUT': '86400', 18:31:07 INFO - 'USER': 'cltbld', 18:31:07 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:07 INFO - '_': '/tools/buildbot/bin/python'} 18:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:07 INFO - Running setup.py (path:/tmp/pip-bTXQJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:07 INFO - Running setup.py (path:/tmp/pip-2VY1_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:07 INFO - Running setup.py (path:/tmp/pip-qx4_Cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:08 INFO - Running setup.py (path:/tmp/pip-SSHHLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:08 INFO - Running setup.py (path:/tmp/pip-pbM4rM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:08 INFO - Running setup.py (path:/tmp/pip-acyiSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:08 INFO - Running setup.py (path:/tmp/pip-RKXZPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:08 INFO - Running setup.py (path:/tmp/pip-ZhtB9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:08 INFO - Running setup.py (path:/tmp/pip-8I7Uwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:08 INFO - Running setup.py (path:/tmp/pip-P8kWAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:08 INFO - Running setup.py (path:/tmp/pip-5uKnc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:08 INFO - Running setup.py (path:/tmp/pip-nJbuUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:08 INFO - Running setup.py (path:/tmp/pip-sqpUXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:08 INFO - Running setup.py (path:/tmp/pip-JZu56c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:08 INFO - Running setup.py (path:/tmp/pip-Aowo_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:08 INFO - Running setup.py (path:/tmp/pip-y7jukB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:08 INFO - Running setup.py (path:/tmp/pip-FAkTh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:10 INFO - Downloading blessings-1.6.tar.gz 18:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:31:10 INFO - Installing collected packages: blessings 18:31:10 INFO - Running setup.py install for blessings 18:31:11 INFO - Successfully installed blessings 18:31:11 INFO - Cleaning up... 18:31:11 INFO - Return code: 0 18:31:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:11 INFO - Reading from file tmpfile_stdout 18:31:11 INFO - Current package versions: 18:31:11 INFO - argparse == 1.2.1 18:31:11 INFO - blessings == 1.6 18:31:11 INFO - blobuploader == 1.2.4 18:31:11 INFO - docopt == 0.6.1 18:31:11 INFO - manifestparser == 1.1 18:31:11 INFO - mozInstall == 1.12 18:31:11 INFO - mozcrash == 0.17 18:31:11 INFO - mozdebug == 0.1 18:31:11 INFO - mozdevice == 0.48 18:31:11 INFO - mozfile == 1.2 18:31:11 INFO - mozhttpd == 0.7 18:31:11 INFO - mozinfo == 0.9 18:31:11 INFO - mozleak == 0.1 18:31:11 INFO - mozlog == 3.1 18:31:11 INFO - moznetwork == 0.27 18:31:11 INFO - mozprocess == 0.22 18:31:11 INFO - mozprofile == 0.28 18:31:11 INFO - mozrunner == 6.11 18:31:11 INFO - mozscreenshot == 0.1 18:31:11 INFO - mozsystemmonitor == 0.0 18:31:11 INFO - moztest == 0.7 18:31:11 INFO - mozversion == 1.4 18:31:11 INFO - psutil == 3.1.1 18:31:11 INFO - requests == 1.2.3 18:31:11 INFO - wsgiref == 0.1.2 18:31:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:11 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:11 INFO - Using env: {'DISPLAY': ':0', 18:31:11 INFO - 'HOME': '/home/cltbld', 18:31:11 INFO - 'LANG': 'en_US.UTF-8', 18:31:11 INFO - 'LANGUAGE': 'en_US:en', 18:31:11 INFO - 'LOGNAME': 'cltbld', 18:31:11 INFO - 'MAIL': '/var/mail/cltbld', 18:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:11 INFO - 'MOZ_NO_REMOTE': '1', 18:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:11 INFO - 'NO_EM_RESTART': '1', 18:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:11 INFO - 'PWD': '/builds/slave/test', 18:31:11 INFO - 'SHELL': '/bin/bash', 18:31:11 INFO - 'SHLVL': '1', 18:31:11 INFO - 'TERM': 'linux', 18:31:11 INFO - 'TMOUT': '86400', 18:31:11 INFO - 'USER': 'cltbld', 18:31:11 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:11 INFO - '_': '/tools/buildbot/bin/python'} 18:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:31:11 INFO - Cleaning up... 18:31:11 INFO - Return code: 0 18:31:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:11 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:31:11 INFO - Using env: {'DISPLAY': ':0', 18:31:11 INFO - 'HOME': '/home/cltbld', 18:31:11 INFO - 'LANG': 'en_US.UTF-8', 18:31:11 INFO - 'LANGUAGE': 'en_US:en', 18:31:11 INFO - 'LOGNAME': 'cltbld', 18:31:11 INFO - 'MAIL': '/var/mail/cltbld', 18:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:11 INFO - 'MOZ_NO_REMOTE': '1', 18:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:11 INFO - 'NO_EM_RESTART': '1', 18:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:11 INFO - 'PWD': '/builds/slave/test', 18:31:11 INFO - 'SHELL': '/bin/bash', 18:31:11 INFO - 'SHLVL': '1', 18:31:11 INFO - 'TERM': 'linux', 18:31:11 INFO - 'TMOUT': '86400', 18:31:11 INFO - 'USER': 'cltbld', 18:31:11 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:11 INFO - '_': '/tools/buildbot/bin/python'} 18:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:11 INFO - Downloading/unpacking jsonschema 18:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:31:13 INFO - Downloading/unpacking functools32 (from jsonschema) 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:13 INFO - Downloading functools32-3.2.3-2.tar.gz 18:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:31:14 INFO - warning: no files found matching '*.txt' 18:31:14 INFO - no previously-included directories found matching 'build' 18:31:14 INFO - no previously-included directories found matching 'dist' 18:31:14 INFO - no previously-included directories found matching '.git*' 18:31:14 INFO - Installing collected packages: jsonschema, functools32 18:31:14 INFO - Running setup.py install for functools32 18:31:14 INFO - warning: no files found matching '*.txt' 18:31:14 INFO - no previously-included directories found matching 'build' 18:31:14 INFO - no previously-included directories found matching 'dist' 18:31:14 INFO - no previously-included directories found matching '.git*' 18:31:14 INFO - Successfully installed jsonschema functools32 18:31:14 INFO - Cleaning up... 18:31:14 INFO - Return code: 0 18:31:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:14 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 0x7f033f4f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, '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': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:31:14 INFO - Using env: {'DISPLAY': ':0', 18:31:14 INFO - 'HOME': '/home/cltbld', 18:31:14 INFO - 'LANG': 'en_US.UTF-8', 18:31:14 INFO - 'LANGUAGE': 'en_US:en', 18:31:14 INFO - 'LOGNAME': 'cltbld', 18:31:14 INFO - 'MAIL': '/var/mail/cltbld', 18:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:14 INFO - 'MOZ_NO_REMOTE': '1', 18:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:14 INFO - 'NO_EM_RESTART': '1', 18:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:14 INFO - 'PWD': '/builds/slave/test', 18:31:14 INFO - 'SHELL': '/bin/bash', 18:31:14 INFO - 'SHLVL': '1', 18:31:14 INFO - 'TERM': 'linux', 18:31:14 INFO - 'TMOUT': '86400', 18:31:14 INFO - 'USER': 'cltbld', 18:31:14 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:14 INFO - '_': '/tools/buildbot/bin/python'} 18:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:14 INFO - Downloading/unpacking flake8 18:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:31:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:31:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:31:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:31:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:31:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:31:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:31:17 INFO - no previously-included directories found matching 'docs/_build' 18:31:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:31:17 INFO - Running setup.py install for pep8 18:31:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:31:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:31:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:31:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:31:17 INFO - no previously-included directories found matching 'docs/_build' 18:31:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:31:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:31:17 INFO - Cleaning up... 18:31:17 INFO - Return code: 0 18:31:17 INFO - Running post-action listener: _resource_record_post_action 18:31:17 INFO - Running post-action listener: _start_resource_monitoring 18:31:17 INFO - Starting resource monitoring. 18:31:17 INFO - ##### 18:31:17 INFO - ##### Running install step. 18:31:17 INFO - ##### 18:31:17 INFO - Running pre-action listener: _resource_record_pre_action 18:31:17 INFO - Running main action method: install 18:31:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:17 INFO - Reading from file tmpfile_stdout 18:31:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:31:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:31:17 INFO - Reading from file tmpfile_stdout 18:31:17 INFO - Output received: 18:31:17 INFO - Usage: mozinstall [options] installer 18:31:17 INFO - Options: 18:31:17 INFO - -h, --help show this help message and exit 18:31:17 INFO - -d DEST, --destination=DEST 18:31:17 INFO - Directory to install application into. [default: 18:31:17 INFO - "/builds/slave/test"] 18:31:17 INFO - --app=APP Application being installed. [default: firefox] 18:31:17 INFO - mkdir: /builds/slave/test/build/application 18:31:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:31:33 INFO - Reading from file tmpfile_stdout 18:31:33 INFO - Output received: 18:31:33 INFO - /builds/slave/test/build/application/firefox/firefox 18:31:33 INFO - Running post-action listener: _resource_record_post_action 18:31:33 INFO - ##### 18:31:33 INFO - ##### Running run-tests step. 18:31:33 INFO - ##### 18:31:33 INFO - Running pre-action listener: _resource_record_pre_action 18:31:33 INFO - Running main action method: run_tests 18:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:33 INFO - Python 2.7.3 18:31:33 INFO - Return code: 0 18:31:33 INFO - grabbing minidump binary from tooltool 18:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:33 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 0x18cd410>, '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 0x18ce310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:31:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:31:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:33 INFO - Return code: 0 18:31:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:31:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:31:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:31:34 INFO - Return code: 0 18:31:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:31:34 INFO - Using env: {'DISPLAY': ':0', 18:31:34 INFO - 'HOME': '/home/cltbld', 18:31:34 INFO - 'LANG': 'en_US.UTF-8', 18:31:34 INFO - 'LANGUAGE': 'en_US:en', 18:31:34 INFO - 'LOGNAME': 'cltbld', 18:31:34 INFO - 'MAIL': '/var/mail/cltbld', 18:31:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:34 INFO - 'MOZ_NO_REMOTE': '1', 18:31:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:31:34 INFO - 'NO_EM_RESTART': '1', 18:31:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:34 INFO - 'PWD': '/builds/slave/test', 18:31:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:34 INFO - 'SHELL': '/bin/bash', 18:31:34 INFO - 'SHLVL': '1', 18:31:34 INFO - 'TERM': 'linux', 18:31:34 INFO - 'TMOUT': '86400', 18:31:34 INFO - 'USER': 'cltbld', 18:31:34 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228', 18:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:34 INFO - '_': '/tools/buildbot/bin/python'} 18:31:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:31:34 INFO - mozversion application_buildid: 20160603160527 18:31:34 INFO - mozversion application_changeset: ab782095ef4ab44dae4cc5f919858c5684837378 18:31:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:31:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:34 INFO - mozversion application_name: Firefox 18:31:34 INFO - mozversion application_remotingname: firefox-dev 18:31:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:31:34 INFO - mozversion application_vendor: Mozilla 18:31:34 INFO - mozversion application_version: 48.0a2 18:31:34 INFO - mozversion platform_buildid: 20160603160527 18:31:34 INFO - mozversion platform_changeset: ab782095ef4ab44dae4cc5f919858c5684837378 18:31:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:31:34 INFO - mozversion platform_version: 48.0a2 18:31:34 INFO - using testdate: 1465003894 18:31:34 INFO - actual date: 1465003894 18:31:34 INFO - starting webserver on 'localhost:52234' 18:31:34 INFO - SUITE-START | Running 2 tests 18:31:34 INFO - TEST-START | dromaeo_css 18:31:34 INFO - operating with platform_type : linux_ 18:31:34 INFO - Initialising browser for dromaeo_css test... 18:31:34 INFO - TEST-INFO | started process 21148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf_Cdp1/profile http://localhost:52234/getInfo.html) 18:31:38 INFO - PROCESS | 21148 | __metrics Screen width/height:1600/1200 18:31:38 INFO - PROCESS | 21148 | colorDepth:24 18:31:38 INFO - PROCESS | 21148 | Browser inner width/height: 1024/697 18:31:38 INFO - PROCESS | 21148 | __metrics 18:31:39 ERROR - PROCESS | 21148 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:31:39 INFO - TEST-INFO | 21148: exit 0 18:31:39 INFO - Browser initialized. 18:31:39 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:39 INFO - TEST-INFO | started process 21238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf_Cdp1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:51 INFO - PROCESS | 21238 | RSS: Main: 141967360 18:31:51 INFO - PROCESS | 21238 | 18:32:42 INFO - PROCESS | 21238 | 1465003962806 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:34:50 INFO - PROCESS | 21238 | Cycle 1(1): loaded http://localhost:52234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52234/tests/dromaeo/cssquery-ext.html) 18:34:51 INFO - PROCESS | 21238 | RSS: Main: 251088896 18:34:51 INFO - PROCESS | 21238 | 18:37:53 INFO - PROCESS | 21238 | Cycle 1(1): loaded http://localhost:52234/tests/dromaeo/cssquery-ext.html (next: http://localhost:52234/tests/dromaeo/cssquery-jquery.html) 18:37:53 INFO - PROCESS | 21238 | RSS: Main: 217985024 18:37:53 INFO - PROCESS | 21238 | 18:40:55 INFO - PROCESS | 21238 | Cycle 1(1): loaded http://localhost:52234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52234/tests/dromaeo/cssquery-mootools.html) 18:40:56 INFO - PROCESS | 21238 | RSS: Main: 220016640 18:40:56 INFO - PROCESS | 21238 | 18:43:58 INFO - PROCESS | 21238 | Cycle 1(1): loaded http://localhost:52234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52234/tests/dromaeo/cssquery-prototype.html) 18:43:58 INFO - PROCESS | 21238 | RSS: Main: 220344320 18:43:58 INFO - PROCESS | 21238 | 18:47:01 INFO - PROCESS | 21238 | Cycle 1(1): loaded http://localhost:52234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52234/tests/dromaeo/cssquery-yui.html) 18:47:01 INFO - PROCESS | 21238 | RSS: Main: 214122496 18:47:01 INFO - PROCESS | 21238 | 18:50:03 INFO - PROCESS | 21238 | Cycle 1(1): loaded http://localhost:52234/tests/dromaeo/cssquery-yui.html (next: http://localhost:52234/tests/dromaeo/cssquery-dojo.html) 18:50:04 INFO - PROCESS | 21238 | RSS: Main: 253874176 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | __start_tp_report 18:50:04 INFO - PROCESS | 21238 | _x_x_mozilla_page_load 18:50:04 INFO - PROCESS | 21238 | _x_x_mozilla_page_load_details 18:50:04 INFO - PROCESS | 21238 | |i|pagename|runs| 18:50:04 INFO - PROCESS | 21238 | |0;dojo.html;3300.669933006699;3343.765936384454;3347.69870711636;3369.578802649669;3379.4254976653965;5496.697681627509;5504.862378440541;5512.448755124486;5516.7517461714215;5541.806036788718;5483.287172667548;5502.257195278637;5522.723863806804;5528.170774383834;5561.5828812839;4805.423349198101;5052.84841454756;5083.694978301295;5125.487451254863;5154.304168937183;4941.604671626261;5045.772940217681;5046.54581087702;5048.6970781753025;5052.115879721053;4931.630127740415;4950.87622809429;4987.226979818134;5014.047331007116;5024.4724303948105;5034.295198672189;5041.193409054552;5051.924221136685;5059.519345662156;5079.390473143228;4866.489018653026;4869.63477739171;4939.827106051271;4980.775865086043;5000.699958002531;5360.973195134038;5403.162509810985;5414;5423.159410291411;5436.483534064258;5808.854778630526;5841.707914604252;5843.941560584382;5847.09370047639;5850.268716410449;5896.439838215363;5898.1152827076285;5933.376995415462;5954.345022047572;5996.910046349309;5497.39528651848;5498.945010549884;5520.834374968757;5539.473749993744;5540.085885828874;5843.824685259449;6100.603460775035;6109.633421994695;6114.663693496811;6115.84710382244;5780.277465316835;5853.619514731528;5861.648301101863;5869.559783016291;5875.970620146872;5860.09168578871;5868.09044598088;5872.7357268923;5872.7357268923;5882.764689412461;5761.049426844607;5822.592418530747;5833.0375488044;5834.066549352168;5843.970780146072;5850.473457388855;5905.261842269716;5922.318933322723;5930.555208359304;5933;4510.2783554631105;4515.322701594721;4516.277395616686;4527.230370836965;4543.5910768031035;3956.169204467094;3999.6600288975765;4052.9392059119136;4058.9188216236103;4076.510818701775;5626.662400255916;5650.604457687922;5658.292713410824;5665.943340566541;5672.688002159921;5917.349091599921;5952.0476723724;5952.8809423812145;5955.52355811534;5958.731857066442;5403;5554.444555544414;5626.099824028136;5672.461116193955;5675.602707810413;5039.168537191395;5062.594992400599;5064.18972964324;5065.822696205615;5066.493350664904;6062.272527296753;6064.9090263646085;6077.149199112039;6077.604955677955;6123.050927106306;5724.341700704473;5875.823725288248;5925.970370148121;5972.13404056418;5982.52139828804;6053.061775424816;6055.273367195965;6062.757489700539;6068.484178844671;6069.393060693896;5699.715014249188;5738.053221218619;5750.9360768258;5751.3673495916255;5757.395473475225;5868.5305175585;5946.316173640087;5946.405359464019;5960.672163031075;5988.401159883977;5730.627509211888;5739.655620662774;5766.077427611562;5773.971130144322;5779.624324418899;5513.42109078557;5690.601657883908;5754.7122643866805;5766.394528574439;5770.0190967536455;5690.146478028329;5704.543636509153;5710.685912274865;5711.114777209539;5746.281714785652;5805.303363596396;5810.331811841692;5812.94187058141;5814.709264536672;5815;5501.752421140978;5624.072028115315;5682.659040457585;5717.085266357363;5723.5707321950185 18:50:04 INFO - PROCESS | 21238 | |1;ext.html;655.9934400655992;673.6564352180388;678.6097993653648;731.2614259597808;752.6462562595585;39440.40839387408;40503.79748101259;40605.59394406055;40635.79682101593;40677.38983915236;43697;43922;44013.33979990295;44126.33810492837;44466;8811.206991370775;8974.192322690957;8978;8983.056779038205;8986.37095403322;8758.299336053133;8878.600462979182;8882.955585222066;9124.817503649923;9129.087091290885;9133.634654613841;9173.862392064124;9186.035466276046;9187.816243675123;9193.678221262257;9115;9161.679341223058;9164.175224229852;9165.908340916572;9166.862497062517;9172.12864777845;9281.90718092824;9283.25733941283;9295.116963888488;9295.628174872996;44025;44152;44400.77799611013;44514.10971780546;44584.10831783346;3987.521497420299;4003.8798836034966;4065.0447144920918;4092.3043082676018;4279.636230920375;33080.50379244313;34119.82940085309;34243.14392140191;34401.82799086013;35457.46813797795;4684.789184486706;4730.95269047312;4731.503192164808;4741.57325840676;4752.120857641347;314914;315260;317224;318151;318204;64689.353106468334;64772.35227647758;65180.348196518375;65220.34779652238;65240.67379663071;67082.64586770642;69981.30018699748;70907;71150;71291.64354178299;19485.63600547948;19843;19858.50353741168;19860.60278794416;19867.403977880687;14044.648883777783;14154.221517816413;14159;14162.929185354007;14163;20446.488837779176;20782.896085519475;20834.27080052191;20873.26943556968;20879;7311.41508679315;7334.96332518334;7374.188839227681;7441.144268409203;7652.196519365498;1022.2639699416409;1063.5214153630898;1164.5691094294996;1171.3323405658693;1255.7551277500982;30867.845660771553;31602.841985789924;32008.199795005312;32071.358572828412;32305.676943230268;1230.7415442756942;1246.8815462531045;1250.3123282194756;1283.1915892987358;1295.4947570447532;13922.303884805719;16809.747853782203;16993.235304411082;17401.216945237495;17832.643347132984;354797;361165;361245;361412;362256;58920;59463.70268148631;59491.40508594859;59699.40300596938;59752.40247597468;1473.454821715972;1515.2348064227715;1529.258309719785;1539.2842328317386;1625.9349626015064;30740.538891917087;30986.69013309928;32921.50617740688;33032.50451243185;33947;53256.467435325154;54333;54363;54428.18357724558;54448;353015;361543;361881;362024;654460;30572.694273057874;31061.378772424876;31322.843385782926;31545;31589.21026974252;45105;45304;45550.54449455463;46458.767706161256;46683;39172.6082739169;41091.589084108775;42479.575204248795;43152.56847431485;43684.78157609192;40992;41513;41603.16793664049;41722.1655566893;42301.57698422977;41735;41786;42254.943626408356;42381.36427953645;42628;36641.267174655826;37156.44265335968;37920.44347891161;37934.62065379311;38592.2281554373;28686.713132868405;28890.27774305575;30495.390092198475;30560.541591875695;30606 18:50:04 INFO - PROCESS | 21238 | |2;jquery.html;407.18156505424105;409.69887132957274;421.111454830308;421.87343796860955;422.5520947795339;18467.815321846778;18883.433496995094;19209;19584.11871465782;19599;18176.36382726605;18364.26542938281;18477.076146192703;19750.90124549375;19772.802271977274;5270.525652691257;5654.236678048463;5855.180274761537;5856.297244330686;5878.147668588073;5507.173923911425;5527.972360138194;5540.750666220029;5557.472040156199;5561.499465048145;5518.365387980373;5775.364709881909;6346.428821406095;6352.650604216767;6355.904661430082;6293.9370606293805;6300.936990630126;6309.77915772948;6309.842253943642;6313.400226978452;5438.564914806806;5506.284183056217;5532.972335138339;5583.3300003999375;5597.412271711452;13392.598222053353;13691.520796772065;13708;20906.5818683627;23146.537069258688;2410.758924107593;2582.496413199427;2659.787217022653;2726.9045583404486;2916.47503449379;9588.520573971344;9745.463999480096;9914.80170396588;9968.202543796478;10298.330608510423;2028.0366825757794;2056.506438454775;2096.297740257016;2115.883626400547;2168.4687251623454;76807;76839.61580192119;79609.20390796133;82083;83391;10445.477726113664;10742.570297188182;10758.677239682822;10922.563097476172;11183.496742646601;15023.323950422257;15200.619984500256;15286.464973725866;15296.235188240544;15309.311081001382;8185.549794761227;8647.567621618893;8655.13448655142;8744.519051452231;8818.29453643707;8478.533680647624;8642.049374568918;8691.608877600524;8798;8837;4713.34013238147;4722.574968252874;4771.73755443447;4836.347093142451;4868.732219727878;1991.6415045291988;2020.5941343048135;2022.7067075274003;2023.352527191314;2027.635025695389;440.2010351212539;1096.703889949725;1128.8476055732535;1145.7536629624672;1158.3571118029406;9841.409515429097;10672.466376681135;11415.258008229439;11429.371384573928;11782.705432364259;446.91508613363357;450.92108880945705;457.1907723329688;460.35320374873356;470.7975570504716;6136.23297087864;6242.406971337716;6266.749330026748;6305.054241863757;6372.490200783926;491170;494390;522790;526326;548221;69303.65348173327;80546;83967.58016209879;97075;98952;437.2151987183014;444.7591220494848;447.6955670144268;447.81863345345187;450.9301058335963;9922.851157232792;10283.691489255334;10407.895921040692;10590.576376944837;10636.52135653882;36694;37695.2460950774;38048;38516.229675406896;38652.420213697376;59103;59446.70276648762;60356.39643603508;60620;61012.69493652681;8649.091845356146;8722.258608018135;8755.168259015383;8891.244244239053;8987.370884038055;9587.952060239653;13449.865501345254;13939.790903136258;13982.510612128526;14204.360803764062;11028.613998510014;11713.414329283672;11810.76378472443;12325.445354958869;12394.132410731163;11406.82889756671;11683.357415342238;11715.414229288332;12002.519899203933;12103.75792484163;7724.841273808974;11274.549018039515;12308.692282693004;12656.936715316364;12815.359232038174;8739.300855931637;8930.553472326228;8967.820643587222;9016.09839016093;9080.772980675536;9219.76950576214;12023;12180.939095304468;12433.564825231073;12697.301648409426 18:50:04 INFO - PROCESS | 21238 | |3;mootools.html;1432.5272660022194;1579.8499142581454;1587.3174534949972;1823.507652933708;1908.30346923373;7725.072991241045;8400.789980250496;8628.784280392985;8628.870566941501;8629.309655227582;8442.324614030878;8615.43999640023;8641.87037194441;8643.74068777936;8646.437981531197;2080.1991233375106;2324.39565712915;2342.0982921575232;2357.905683772647;2377.976220237793;2272.5682120397073;2277.4647957730003;2282.543491301738;2282.6804247405375;2287.1080278691325;2284.657301404786;2323.8721870297127;2351.1535847095033;2351.8000969917575;2355.5759963206615;2053.1787285085934;2283.6117859963847;2336.3575016870495;2346.2257455039794;2377.0254195779735;2258.8080013198896;2294.8852557372234;2332.0322066342446;2332.3935776698013;2343.437574982009;8527.74416767498;8530.74407767768;8574.314054875595;8591.6992905248;8592.5703714814;5272.4200337963175;5341.519263266325;5383.515483606494;5384.326959130109;5384.67691938481;3432.6567343265724;3452.7583069185166;3500.422430298998;3580.838862251179;3588.1926566522584;1117.9217454778168;1125.0549538387793;1128.4696192789377;1152.8962393384552;1176.6940595445246;12418.068644851583;12429.067819913542;12486.75026499474;12501.93749031258;12510.061745369134;4535.25168347219;4573.63410927125;4615.469221039556;4633.351330813689;4647.419072615923;10636.095931845879;10637.787244255072;10639.840402393971;10650.307729997525;10651.094656954245;5371.086915224421;5378.085725426687;5378.865528361822;5379.67721936685;5383.757730902119;4354.586314300136;4749;4824.6863953842885;4837.32277481153;4840.685355451885;4213.115245798416;4238.406623072772;4275.5724427557;4336.566343365638;4446.444194475691;1349.305107869448;1387.7987691784635;1397.2873834344355;1398.797174409705;1419.22652154576;1000.2098342309639;1006.33079002464;1007.1791489935679;1022.6727447216967;1024.6772266735954;6056.121862329937;6056.939430605726;6108;6175.258968923668;6207.96896015517;429.76363000350307;442.67684590249297;444.69760562817595;445.1075593435142;447.64635937609575;4089.20260549196;4130.297849365615;4165.312723400605;4165.520965088993;4178.91642167155;9979.700608981742;10125.848112278321;10184.745381365377;10255.9487202565;10263.332883362558;10058.74853128663;10089.445080520642;10111.393316400892;10114.797704045877;10118.038786315288;7848.686052557948;8031.196880311922;8034.83930321402;8068.757937261891;8082.232187942136;5851.795187168429;5982.461578457785;6081.908771368521;6084.39156084388;6172.351903050295;9730.46482443444;9833.459159746282;10044.698659040241;10048.849267261143;10093.949530252301;9374.109459601326;9565.091316324939;9705.514724263618;9968.053034961667;10006.949965250127;7064.258252883374;7165.749198778018;7603.619819008917;7751.069871615442;7851.725189618336;8288.461250018729;8324.16758324187;8360.498370097814;8374.246317831425;8391.832163356821;7071.858562828817;7331.04696389466;7334.889976650248;7345.522541034816;7451.813704657427;7155.535447046957;7453.217412171861;7470.775876723707;7540.736074237376;7565.205653406313;7133.037039999534;7135.714571417086;7147.463940204401;7168.319009694071;7185.317394847481;5160.380754309507;5208.374995000624;5217.191335343028;5367.624266301322;5409.918851217313;5282.97358513205;5287.762050707651;5326.520613144836;5389.56883449331;5476.91784623223 18:50:04 INFO - PROCESS | 21238 | |4;prototype.html;322.91765599772054;330.89907578188644;332.03378169526684;337.3994290163509;339.6111452387016;6127.111568822518;6171.845703857406;6183.288921773992;6219.098230756537;6219.657918814475;6140.171076904617;6140.81577552673;6143.754249830002;6156.815295541141;6160.969195154018;4504.977475112628;4659.184642687533;4731.195696731564;4744.288356746481;4746.454157771848;4702.882427939295;4734.431868175824;4742.33607294979;4752.405949256343;4754.3343931849395;4444.577765112309;4699.0836786826585;4701.623870090384;4703.835365762199;4711.363965864599;4582.747948862811;4684.765761711918;4699.835505757316;4702.52974702527;4716.434027916672;4123.422720819088;4130.339145736668;4142.482189726285;4146.730462519927;4155.231282212801;5949.8810023799715;5953.374895635982;5955.791547295824;5960.284765828085;5966.194563733927;5481.643693159972;5483.561315094823;5488.341399032101;5488.780448782044;5490.203920431555;6352.269489008777;6483.384078512534;6488.578242414275;6489.545731798776;6492.156019717454;4561.384213131251;4594.816207351702;4603.723776573416;4604.194266003436;4609.976950115261;6723.428508576777;6730.427913627299;6752.291009444036;6767.154105736783;6769.661516924183;4708.105459962596;4708.458527269408;4709.62323014158;4710.52894710533;4715.95284047162;4773.570378665937;4775.71345719258;4776.689515181502;4776.761161941889;4781.521847815191;5460.918086228709;5461.290032295776;5469.726513674301;5474.069408200616;5476.288082549243;6359.332270111664;6527;6527.836804079936;6529.2817790043055;6536.542442029012;1609.26778315866;1615.4345978907288;1617.7896873406617;1618.0696099742695;1633.6242664187066;1035.3270374256792;1085.5060947269133;1093.5953697131954;1106.645873320546;1136.5567428702811;6264.404881536247;6329.620222786648;6366.808995730135;6383.2978372379;6383.425491705676;6649.933500664932;6661.500387470862;6667.433268172163;6670.533062685662;6675.7997260082275;6296.5592408531875;6298.842528936813;6302.621842689453;6305.558610897193;6310.0850376695125;3535.116220944764;3565.714742820568;3593.6765691087403;3598.874039408608;3603.117236277129;6731.148539670318;6911.861762764716;6914.619695916672;6953.548019378825;6957;6702.698378572976;6709.865802683919;6742.224644165885;6752;6773.085633439521;6907.7236910524025;6914.204866440323;6915.861682766316;6917.412019978358;6919.4464442844455;6705.362990515893;6709.664516774239;6712.093867327848;6714.227863795726;6720.294369091371;6495.935040649533;6570;6764.053032575345;6772.424343930624;6776.661166941732;7008.369246767816;7035.613041282779;7045.365917067488;7051.471139664648;7061.788146355618;4528.2075636762775;4560.657950653648;4564.88587785308;4573.748443835621;4576.832907608538;6767.323267673193;6771.322867713189;6772.88247439167;6776.017477465642;6776.017477465838;6394.328595497405;6565.048068030204;6578.407943285128;6579;6579.605223686594;6535.411812936859;6557.278699343164;6558.934410655832;6561.671916404065;6562.3109573496;6403.295637479967;6441.452476539546;6442.452391546771;6445.6454894981225;6465;6581.967090164519;6584.8024559263295;6608.4052435281055;6619.271880093283;6681.09805176295;6097.817065488042;6106.480949119375;6146.354632763496;6478.060681201294;6481.708323125565 18:50:04 INFO - PROCESS | 21238 | |5;yui.html;722.3643193989288;733.3912852332564;733.8458923626039;759.8176437654963;826.883706995556;13707.971902107332;13794.31028448576;13824.654383640414;13831.654208644788;13910.582682519527;12315.261084334947;12480.313582752944;12556.81164782529;13596.116252443584;13604.183748975069;890.799570096728;900.0549423105713;908.0193391137556;908.8091500784843;909.0863681999581;895.2837729816154;900.5677274908015;900.6982660808604;901.0133903375826;901.432097778402;932.3753085432776;932.8367535681263;933.12752576341;934.0052843721448;934.6588495199237;929.1498279074639;930.3952430919923;930.6510058727977;930.702175303903;930.9813803723888;668.6656671664168;673.3367632881608;675.5710124071201;675.9560628559128;677.0555075669463;12460.937695311555;12481.75036499274;12492.188007779465;12512.937435312766;12544.937275313656;11732.413379331;11967.461464234044;12313.97405074616;12454.875451245462;12721.363931803466;10498.580056797718;10695.78608427827;10702.197335199891;10792.298500597515;10839.18706097038;3737.1030952571186;3738.2897249522503;3792.241551689673;3792.5259342582176;3903.5315762108444;46623.5337646626;54246.45753542453;54786.45213547893;54847.72576137134;57165.714171429296;11279.661610151708;11426.828597571042;11584.42077896102;11585.590062111802;11817.704557385961;19277.51806204828;19293.228270869287;19324.516887077934;19339.12973916177;19346.129424175946;8474;8513.063563008189;8581.570921453902;8611.741647750578;8822.911770882209;4702.083093796745;4936.580390666833;4963.726995015309;5033.546980771748;5033.672811267256;3426.8971930842113;3456.2396272819938;3479.878204262889;3485.5643044619424;3488.860445582199;610.5664977865765;619.8884200843801;622.6015350175891;626.4831514000967;628.1959092361784;703.0298188499837;749.2769477454283;761.5735188294573;786.9960650196713;821.0311832038252;9760.267979901535;9838.803223935482;9861.852072218922;9935.602575897028;9956;681.6148875885109;721.6572128239045;763.5723994562951;765.8927750114989;869.9391042626955;4517.322401639714;4539.500654927955;4688.249880019181;4714.22215334466;4786.2820576913045;64786.35213647898;66214.66892665505;66344.66827665927;66686;66714.66642666853;55440.16839747407;57530;58291.708541457025;59121.40878591245;59844.70077649584;53558;53881.1917821233;53947.46052539424;54890.45109548853;55515;9315.02192269829;9379.859302110337;9405.905940940504;9487.193588544775;9543.42739435636;37017.25965480729;39267.80366098265;39952.60047399605;39958.60041399549;40583;42463.57536424596;45365;49379.50620493749;49913.251301231234;50237.74881125571;11289.943550282525;11506.137039721887;11710.882891170979;11804.468798904241;12110.576129835414;11540.538378464767;12369.62891113268;12385.504579816707;12508.437120329425;12621.05342099328;11346.773064538827;11617;12017.579384721494;12030.338331391857;12179.756404871676;11090.889091108986;11153.107751379708;11247.550097996316;11365.204435689422;11511.30932144074;11064.834027489755;11429.657110286706;11431.256968297033;11529.423528823692;12066.879331206575;9871.555779989774;9928.468670076518;10103.808987476106;10398.410913576292;10476.266661333633;10189.5004620749;10605.478399297384;10613.787724245318;10679.733006674895;10794.298370605886 18:50:04 INFO - PROCESS | 21238 | __end_tp_report 18:50:04 INFO - PROCESS | 21238 | __start_cc_report 18:50:04 INFO - PROCESS | 21238 | _x_x_mozilla_cycle_collect,853 18:50:04 INFO - PROCESS | 21238 | __end_cc_report 18:50:04 INFO - PROCESS | 21238 | __startTimestamp1465005004109__endTimestamp 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | ------- Summary: start ------- 18:50:04 INFO - PROCESS | 21238 | Number of tests: 6 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | [#0] dojo.html Cycles:165 Average:5478.06 Median:5704.54 stddev:603.59 (10.6%) stddev-sans-first:580.77 18:50:04 INFO - PROCESS | 21238 | Values: 3300.7 3343.8 3347.7 3369.6 3379.4 5496.7 5504.9 5512.4 5516.8 5541.8 5483.3 5502.3 5522.7 5528.2 5561.6 4805.4 5052.8 5083.7 5125.5 5154.3 4941.6 5045.8 5046.5 5048.7 5052.1 4931.6 4950.9 4987.2 5014.0 5024.5 5034.3 5041.2 5051.9 5059.5 5079.4 4866.5 4869.6 4939.8 4980.8 5000.7 5361.0 5403.2 5414.0 5423.2 5436.5 5808.9 5841.7 5843.9 5847.1 5850.3 5896.4 5898.1 5933.4 5954.3 5996.9 5497.4 5498.9 5520.8 5539.5 5540.1 5843.8 6100.6 6109.6 6114.7 6115.8 5780.3 5853.6 5861.6 5869.6 5876.0 5860.1 5868.1 5872.7 5872.7 5882.8 5761.0 5822.6 5833.0 5834.1 5844.0 5850.5 5905.3 5922.3 5930.6 5933.0 4510.3 4515.3 4516.3 4527.2 4543.6 3956.2 3999.7 4052.9 4058.9 4076.5 5626.7 5650.6 5658.3 5665.9 5672.7 5917.3 5952.0 5952.9 5955.5 5958.7 5403.0 5554.4 5626.1 5672.5 5675.6 5039.2 5062.6 5064.2 5065.8 5066.5 6062.3 6064.9 6077.1 6077.6 6123.1 5724.3 5875.8 5926.0 5972.1 5982.5 6053.1 6055.3 6062.8 6068.5 6069.4 5699.7 5738.1 5750.9 5751.4 5757.4 5868.5 5946.3 5946.4 5960.7 5988.4 5730.6 5739.7 5766.1 5774.0 5779.6 5513.4 5690.6 5754.7 5766.4 5770.0 5690.1 5704.5 5710.7 5711.1 5746.3 5805.3 5810.3 5812.9 5814.7 5815.0 5501.8 5624.1 5682.7 5717.1 5723.6 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | [#1] ext.html Cycles:180 Average:54978.60 Median:41302.29 stddev:98484.49 (238.4%) stddev-sans-first:98676.31 18:50:04 INFO - PROCESS | 21238 | Values: 656.0 673.7 678.6 731.3 752.6 39440.4 40503.8 40605.6 40635.8 40677.4 43697.0 43922.0 44013.3 44126.3 44466.0 8811.2 8974.2 8978.0 8983.1 8986.4 8758.3 8878.6 8883.0 9124.8 9129.1 9133.6 9173.9 9186.0 9187.8 9193.7 9115.0 9161.7 9164.2 9165.9 9166.9 9172.1 9281.9 9283.3 9295.1 9295.6 44025.0 44152.0 44400.8 44514.1 44584.1 3987.5 4003.9 4065.0 4092.3 4279.6 33080.5 34119.8 34243.1 34401.8 35457.5 4684.8 4731.0 4731.5 4741.6 4752.1 314914.0 315260.0 317224.0 318151.0 318204.0 64689.4 64772.4 65180.3 65220.3 65240.7 67082.6 69981.3 70907.0 71150.0 71291.6 19485.6 19843.0 19858.5 19860.6 19867.4 14044.6 14154.2 14159.0 14162.9 14163.0 20446.5 20782.9 20834.3 20873.3 20879.0 7311.4 7335.0 7374.2 7441.1 7652.2 1022.3 1063.5 1164.6 1171.3 1255.8 30867.8 31602.8 32008.2 32071.4 32305.7 1230.7 1246.9 1250.3 1283.2 1295.5 13922.3 16809.7 16993.2 17401.2 17832.6 354797.0 361165.0 361245.0 361412.0 362256.0 58920.0 59463.7 59491.4 59699.4 59752.4 1473.5 1515.2 1529.3 1539.3 1625.9 30740.5 30986.7 32921.5 33032.5 33947.0 53256.5 54333.0 54363.0 54428.2 54448.0 353015.0 361543.0 361881.0 362024.0 654460.0 30572.7 31061.4 31322.8 31545.0 31589.2 45105.0 45304.0 45550.5 46458.8 46683.0 39172.6 41091.6 42479.6 43152.6 43684.8 40992.0 41513.0 41603.2 41722.2 42301.6 41735.0 41786.0 42254.9 42381.4 42628.0 36641.3 37156.4 37920.4 37934.6 38592.2 28686.7 28890.3 30495.4 30560.5 30606.0 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | [#2] jquery.html Cycles:180 Average:28798.49 Median:449.37 stddev:85117.37 (18941.5%) stddev-sans-first:85329.45 18:50:04 INFO - PROCESS | 21238 | Values: 407.2 409.7 421.1 421.9 422.6 18467.8 18883.4 19209.0 19584.1 19599.0 18176.4 18364.3 18477.1 19750.9 19772.8 5270.5 5654.2 5855.2 5856.3 5878.1 5507.2 5528.0 5540.8 5557.5 5561.5 5518.4 5775.4 6346.4 6352.7 6355.9 6293.9 6300.9 6309.8 6309.8 6313.4 5438.6 5506.3 5533.0 5583.3 5597.4 13392.6 13691.5 13708.0 20906.6 23146.5 2410.8 2582.5 2659.8 2726.9 2916.5 9588.5 9745.5 9914.8 9968.2 10298.3 2028.0 2056.5 2096.3 2115.9 2168.5 76807.0 76839.6 79609.2 82083.0 83391.0 10445.5 10742.6 10758.7 10922.6 11183.5 15023.3 15200.6 15286.5 15296.2 15309.3 8185.5 8647.6 8655.1 8744.5 8818.3 8478.5 8642.0 8691.6 8798.0 8837.0 4713.3 4722.6 4771.7 4836.3 4868.7 1991.6 2020.6 2022.7 2023.4 2027.6 440.2 1096.7 1128.8 1145.8 1158.4 9841.4 10672.5 11415.3 11429.4 11782.7 446.9 450.9 457.2 460.4 470.8 6136.2 6242.4 6266.7 6305.1 6372.5 491170.0 494390.0 522790.0 526326.0 548221.0 69303.7 80546.0 83967.6 97075.0 98952.0 437.2 444.8 447.7 447.8 450.9 9922.9 10283.7 10407.9 10590.6 10636.5 36694.0 37695.2 38048.0 38516.2 38652.4 59103.0 59446.7 60356.4 60620.0 61012.7 8649.1 8722.3 8755.2 8891.2 8987.4 9588.0 13449.9 13939.8 13982.5 14204.4 11028.6 11713.4 11810.8 12325.4 12394.1 11406.8 11683.4 11715.4 12002.5 12103.8 7724.8 11274.5 12308.7 12656.9 12815.4 8739.3 8930.6 8967.8 9016.1 9080.8 9219.8 12023.0 12180.9 12433.6 12697.3 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | [#3] mootools.html Cycles:180 Average:5730.66 Median:4594.55 stddev:3200.03 (69.6%) stddev-sans-first:3192.71 18:50:04 INFO - PROCESS | 21238 | Values: 1432.5 1579.8 1587.3 1823.5 1908.3 7725.1 8400.8 8628.8 8628.9 8629.3 8442.3 8615.4 8641.9 8643.7 8646.4 2080.2 2324.4 2342.1 2357.9 2378.0 2272.6 2277.5 2282.5 2282.7 2287.1 2284.7 2323.9 2351.2 2351.8 2355.6 2053.2 2283.6 2336.4 2346.2 2377.0 2258.8 2294.9 2332.0 2332.4 2343.4 8527.7 8530.7 8574.3 8591.7 8592.6 5272.4 5341.5 5383.5 5384.3 5384.7 3432.7 3452.8 3500.4 3580.8 3588.2 1117.9 1125.1 1128.5 1152.9 1176.7 12418.1 12429.1 12486.8 12501.9 12510.1 4535.3 4573.6 4615.5 4633.4 4647.4 10636.1 10637.8 10639.8 10650.3 10651.1 5371.1 5378.1 5378.9 5379.7 5383.8 4354.6 4749.0 4824.7 4837.3 4840.7 4213.1 4238.4 4275.6 4336.6 4446.4 1349.3 1387.8 1397.3 1398.8 1419.2 1000.2 1006.3 1007.2 1022.7 1024.7 6056.1 6056.9 6108.0 6175.3 6208.0 429.8 442.7 444.7 445.1 447.6 4089.2 4130.3 4165.3 4165.5 4178.9 9979.7 10125.8 10184.7 10255.9 10263.3 10058.7 10089.4 10111.4 10114.8 10118.0 7848.7 8031.2 8034.8 8068.8 8082.2 5851.8 5982.5 6081.9 6084.4 6172.4 9730.5 9833.5 10044.7 10048.8 10093.9 9374.1 9565.1 9705.5 9968.1 10006.9 7064.3 7165.7 7603.6 7751.1 7851.7 8288.5 8324.2 8360.5 8374.2 8391.8 7071.9 7331.0 7334.9 7345.5 7451.8 7155.5 7453.2 7470.8 7540.7 7565.2 7133.0 7135.7 7147.5 7168.3 7185.3 5160.4 5208.4 5217.2 5367.6 5409.9 5283.0 5287.8 5326.5 5389.6 5476.9 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | [#4] prototype.html Cycles:180 Average:5460.27 Median:6201.19 stddev:1644.27 (26.5%) stddev-sans-first:1603.04 18:50:04 INFO - PROCESS | 21238 | Values: 322.9 330.9 332.0 337.4 339.6 6127.1 6171.8 6183.3 6219.1 6219.7 6140.2 6140.8 6143.8 6156.8 6161.0 4505.0 4659.2 4731.2 4744.3 4746.5 4702.9 4734.4 4742.3 4752.4 4754.3 4444.6 4699.1 4701.6 4703.8 4711.4 4582.7 4684.8 4699.8 4702.5 4716.4 4123.4 4130.3 4142.5 4146.7 4155.2 5949.9 5953.4 5955.8 5960.3 5966.2 5481.6 5483.6 5488.3 5488.8 5490.2 6352.3 6483.4 6488.6 6489.5 6492.2 4561.4 4594.8 4603.7 4604.2 4610.0 6723.4 6730.4 6752.3 6767.2 6769.7 4708.1 4708.5 4709.6 4710.5 4716.0 4773.6 4775.7 4776.7 4776.8 4781.5 5460.9 5461.3 5469.7 5474.1 5476.3 6359.3 6527.0 6527.8 6529.3 6536.5 1609.3 1615.4 1617.8 1618.1 1633.6 1035.3 1085.5 1093.6 1106.6 1136.6 6264.4 6329.6 6366.8 6383.3 6383.4 6649.9 6661.5 6667.4 6670.5 6675.8 6296.6 6298.8 6302.6 6305.6 6310.1 3535.1 3565.7 3593.7 3598.9 3603.1 6731.1 6911.9 6914.6 6953.5 6957.0 6702.7 6709.9 6742.2 6752.0 6773.1 6907.7 6914.2 6915.9 6917.4 6919.4 6705.4 6709.7 6712.1 6714.2 6720.3 6495.9 6570.0 6764.1 6772.4 6776.7 7008.4 7035.6 7045.4 7051.5 7061.8 4528.2 4560.7 4564.9 4573.7 4576.8 6767.3 6771.3 6772.9 6776.0 6776.0 6394.3 6565.0 6578.4 6579.0 6579.6 6535.4 6557.3 6558.9 6561.7 6562.3 6403.3 6441.5 6442.5 6445.6 6465.0 6582.0 6584.8 6608.4 6619.3 6681.1 6097.8 6106.5 6146.4 6478.1 6481.7 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - PROCESS | 21238 | [#5] yui.html Cycles:180 Average:15089.94 Median:4750.25 stddev:18096.58 (381.0%) stddev-sans-first:18115.18 18:50:04 INFO - PROCESS | 21238 | Values: 722.4 733.4 733.8 759.8 826.9 13708.0 13794.3 13824.7 13831.7 13910.6 12315.3 12480.3 12556.8 13596.1 13604.2 890.8 900.1 908.0 908.8 909.1 895.3 900.6 900.7 901.0 901.4 932.4 932.8 933.1 934.0 934.7 929.1 930.4 930.7 930.7 931.0 668.7 673.3 675.6 676.0 677.1 12460.9 12481.8 12492.2 12512.9 12544.9 11732.4 11967.5 12314.0 12454.9 12721.4 10498.6 10695.8 10702.2 10792.3 10839.2 3737.1 3738.3 3792.2 3792.5 3903.5 46623.5 54246.5 54786.5 54847.7 57165.7 11279.7 11426.8 11584.4 11585.6 11817.7 19277.5 19293.2 19324.5 19339.1 19346.1 8474.0 8513.1 8581.6 8611.7 8822.9 4702.1 4936.6 4963.7 5033.5 5033.7 3426.9 3456.2 3479.9 3485.6 3488.9 610.6 619.9 622.6 626.5 628.2 703.0 749.3 761.6 787.0 821.0 9760.3 9838.8 9861.9 9935.6 9956.0 681.6 721.7 763.6 765.9 869.9 4517.3 4539.5 4688.2 4714.2 4786.3 64786.4 66214.7 66344.7 66686.0 66714.7 55440.2 57530.0 58291.7 59121.4 59844.7 53558.0 53881.2 53947.5 54890.5 55515.0 9315.0 9379.9 9405.9 9487.2 9543.4 37017.3 39267.8 39952.6 39958.6 40583.0 42463.6 45365.0 49379.5 49913.3 50237.7 11289.9 11506.1 11710.9 11804.5 12110.6 11540.5 12369.6 12385.5 12508.4 12621.1 11346.8 11617.0 12017.6 12030.3 12179.8 11090.9 11153.1 11247.6 11365.2 11511.3 11064.8 11429.7 11431.3 11529.4 12066.9 9871.6 9928.5 10103.8 10398.4 10476.3 10189.5 10605.5 10613.8 10679.7 10794.3 18:50:04 INFO - PROCESS | 21238 | -------- Summary: end -------- 18:50:04 INFO - PROCESS | 21238 | 18:50:04 INFO - TEST-INFO | 21238: exit 0 18:50:04 INFO - TEST-OK | dromaeo_css | took 1110434ms 18:50:04 INFO - TEST-START | kraken 18:50:04 INFO - operating with platform_type : linux_ 18:50:04 INFO - Initialising browser for kraken test... 18:50:04 INFO - TEST-INFO | started process 21426 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE8n31M/profile http://localhost:52234/getInfo.html) 18:50:08 INFO - PROCESS | 21426 | __metrics Screen width/height:1600/1200 18:50:08 INFO - PROCESS | 21426 | colorDepth:24 18:50:08 INFO - PROCESS | 21426 | Browser inner width/height: 1024/697 18:50:08 INFO - PROCESS | 21426 | __metrics 18:50:09 ERROR - PROCESS | 21426 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:50:10 INFO - TEST-INFO | 21426: exit 0 18:50:10 INFO - Browser initialized. 18:50:10 INFO - Running cycle 1/1 for kraken test... 18:50:10 INFO - TEST-INFO | started process 21520 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE8n31M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:22 INFO - PROCESS | 21520 | RSS: Main: 142180352 18:50:22 INFO - PROCESS | 21520 | 18:50:24 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52234/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:25 INFO - PROCESS | 21520 | RSS: Main: 196493312 18:50:25 INFO - PROCESS | 21520 | 18:50:28 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52234/tests/kraken/driver.html?testName=audio-dft) 18:50:28 INFO - PROCESS | 21520 | RSS: Main: 180400128 18:50:28 INFO - PROCESS | 21520 | 18:50:31 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52234/tests/kraken/driver.html?testName=audio-fft) 18:50:32 INFO - PROCESS | 21520 | RSS: Main: 272011264 18:50:32 INFO - PROCESS | 21520 | 18:50:34 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52234/tests/kraken/driver.html?testName=audio-oscillator) 18:50:35 INFO - PROCESS | 21520 | RSS: Main: 178458624 18:50:35 INFO - PROCESS | 21520 | 18:50:37 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:50:37 INFO - PROCESS | 21520 | RSS: Main: 176910336 18:50:37 INFO - PROCESS | 21520 | 18:50:40 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52234/tests/kraken/driver.html?testName=imaging-darkroom) 18:50:41 INFO - PROCESS | 21520 | RSS: Main: 211230720 18:50:41 INFO - PROCESS | 21520 | 18:50:44 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52234/tests/kraken/driver.html?testName=imaging-desaturate) 18:50:45 INFO - PROCESS | 21520 | RSS: Main: 210468864 18:50:45 INFO - PROCESS | 21520 | 18:50:48 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52234/tests/kraken/driver.html?testName=json-parse-financial) 18:50:48 INFO - PROCESS | 21520 | RSS: Main: 211210240 18:50:48 INFO - PROCESS | 21520 | 18:50:50 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:50 INFO - PROCESS | 21520 | RSS: Main: 191860736 18:50:50 INFO - PROCESS | 21520 | 18:50:52 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:53 INFO - PROCESS | 21520 | RSS: Main: 193306624 18:50:53 INFO - PROCESS | 21520 | 18:50:55 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:55 INFO - PROCESS | 21520 | RSS: Main: 207126528 18:50:55 INFO - PROCESS | 21520 | 18:50:58 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:58 INFO - PROCESS | 21520 | RSS: Main: 209707008 18:50:58 INFO - PROCESS | 21520 | 18:51:01 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:51:02 INFO - PROCESS | 21520 | RSS: Main: 206262272 18:51:02 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | Cycle 1(1): loaded http://localhost:52234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52234/tests/kraken/driver.html?testName=ai-astar) 18:51:03 INFO - PROCESS | 21520 | RSS: Main: 270487552 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | __start_tp_report 18:51:03 INFO - PROCESS | 21520 | _x_x_mozilla_page_load 18:51:03 INFO - PROCESS | 21520 | _x_x_mozilla_page_load_details 18:51:03 INFO - PROCESS | 21520 | |i|pagename|runs| 18:51:03 INFO - PROCESS | 21520 | |0;ai-astar;112;113;111;111;111;111;107;109;111;110 18:51:03 INFO - PROCESS | 21520 | |1;audio-beat-detection;158;124;155;178;138;163;158;134;189;127 18:51:03 INFO - PROCESS | 21520 | |2;audio-dft;162;161;162;161;161;161;162;160;161;160 18:51:03 INFO - PROCESS | 21520 | |3;audio-fft;77;74;77;179;77;74;77;75;170;76 18:51:03 INFO - PROCESS | 21520 | |4;audio-oscillator;92;93;148;94;93;92;145;93;92;142 18:51:03 INFO - PROCESS | 21520 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;117;116 18:51:03 INFO - PROCESS | 21520 | |6;imaging-darkroom;146;145;145;145;146;146;145;145;147;145 18:51:03 INFO - PROCESS | 21520 | |7;imaging-desaturate;94;95;94;95;95;95;95;94;95;95 18:51:03 INFO - PROCESS | 21520 | |8;json-parse-financial;56;54;54;54;55;55;54;86;53;53 18:51:03 INFO - PROCESS | 21520 | |9;json-stringify-tinderbox;58;59;59;57;58;58;58;59;59;58 18:51:03 INFO - PROCESS | 21520 | |10;stanford-crypto-aes;76;72;71;73;73;73;80;72;74;80 18:51:03 INFO - PROCESS | 21520 | |11;stanford-crypto-ccm;117;127;112;113;110;111;114;116;161;120 18:51:03 INFO - PROCESS | 21520 | |12;stanford-crypto-pbkdf2;161;164;153;155;157;161;160;156;158;159 18:51:03 INFO - PROCESS | 21520 | |13;stanford-crypto-sha256-iterative;69;65;67;66;64;66;67;66;66;62 18:51:03 INFO - PROCESS | 21520 | __end_tp_report 18:51:03 INFO - PROCESS | 21520 | __start_cc_report 18:51:03 INFO - PROCESS | 21520 | _x_x_mozilla_cycle_collect,1600 18:51:03 INFO - PROCESS | 21520 | __end_cc_report 18:51:03 INFO - PROCESS | 21520 | __startTimestamp1465005063961__endTimestamp 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | ------- Summary: start ------- 18:51:03 INFO - PROCESS | 21520 | Number of tests: 14 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.65 (1.5%) stddev-sans-first:1.67 18:51:03 INFO - PROCESS | 21520 | Values: 112.0 113.0 111.0 111.0 111.0 111.0 107.0 109.0 111.0 110.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#1] audio-beat-detection Cycles:10 Average:152.40 Median:158.00 stddev:21.54 (13.6%) stddev-sans-first:22.75 18:51:03 INFO - PROCESS | 21520 | Values: 158.0 124.0 155.0 178.0 138.0 163.0 158.0 134.0 189.0 127.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 18:51:03 INFO - PROCESS | 21520 | Values: 162.0 161.0 162.0 161.0 161.0 161.0 162.0 160.0 161.0 160.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#3] audio-fft Cycles:10 Average:95.60 Median:76.50 stddev:41.66 (54.5%) stddev-sans-first:43.63 18:51:03 INFO - PROCESS | 21520 | Values: 77.0 74.0 77.0 179.0 77.0 74.0 77.0 75.0 170.0 76.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#4] audio-oscillator Cycles:10 Average:108.40 Median:92.50 stddev:25.30 (27.4%) stddev-sans-first:26.13 18:51:03 INFO - PROCESS | 21520 | Values: 92.0 93.0 148.0 94.0 93.0 92.0 145.0 93.0 92.0 142.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 18:51:03 INFO - PROCESS | 21520 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 18:51:03 INFO - PROCESS | 21520 | Values: 146.0 145.0 145.0 145.0 146.0 146.0 145.0 145.0 147.0 145.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 18:51:03 INFO - PROCESS | 21520 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:54.50 stddev:10.09 (18.5%) stddev-sans-first:10.69 18:51:03 INFO - PROCESS | 21520 | Values: 56.0 54.0 54.0 54.0 55.0 55.0 54.0 86.0 53.0 53.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.50 stddev:0.67 (1.2%) stddev-sans-first:0.71 18:51:03 INFO - PROCESS | 21520 | Values: 58.0 59.0 59.0 57.0 58.0 58.0 58.0 59.0 59.0 58.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:73.50 stddev:3.24 (4.4%) stddev-sans-first:3.38 18:51:03 INFO - PROCESS | 21520 | Values: 76.0 72.0 71.0 73.0 73.0 73.0 80.0 72.0 74.0 80.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#11] stanford-crypto-ccm Cycles:10 Average:120.10 Median:116.50 stddev:15.22 (13.1%) stddev-sans-first:16.10 18:51:03 INFO - PROCESS | 21520 | Values: 117.0 127.0 112.0 113.0 110.0 111.0 114.0 116.0 161.0 120.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.40 Median:159.50 stddev:3.27 (2.1%) stddev-sans-first:3.33 18:51:03 INFO - PROCESS | 21520 | Values: 161.0 164.0 153.0 155.0 157.0 161.0 160.0 156.0 158.0 159.0 18:51:03 INFO - PROCESS | 21520 | 18:51:03 INFO - PROCESS | 21520 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.87 (2.8%) stddev-sans-first:1.59 18:51:03 INFO - PROCESS | 21520 | Values: 69.0 65.0 67.0 66.0 64.0 66.0 67.0 66.0 66.0 62.0 18:51:03 INFO - PROCESS | 21520 | -------- Summary: end -------- 18:51:03 INFO - PROCESS | 21520 | 18:51:04 INFO - TEST-INFO | 21520: exit 0 18:51:04 INFO - TEST-OK | kraken | took 60068ms 18:51:04 INFO - SUITE-END | took 1170s 18:51:05 INFO - Completed test suite (00:19:30) 18:51:05 INFO - javascript benchmark 18:51:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.669933006699, 3343.765936384454, 3347.69870711636, 3369.578802649669, 3379.4254976653965, 5496.697681627509, 5504.862378440541, 5512.448755124486, 5516.7517461714215, 5541.806036788718, 5483.287172667548, 5502.257195278637, 5522.723863806804, 5528.170774383834, 5561.5828812839, 4805.423349198101, 5052.84841454756, 5083.694978301295, 5125.487451254863, 5154.304168937183, 4941.604671626261, 5045.772940217681, 5046.54581087702, 5048.6970781753025, 5052.115879721053, 4931.630127740415, 4950.87622809429, 4987.226979818134, 5014.047331007116, 5024.4724303948105, 5034.295198672189, 5041.193409054552, 5051.924221136685, 5059.519345662156, 5079.390473143228, 4866.489018653026, 4869.63477739171, 4939.827106051271, 4980.775865086043, 5000.699958002531, 5360.973195134038, 5403.162509810985, 5414.0, 5423.159410291411, 5436.483534064258, 5808.854778630526, 5841.707914604252, 5843.941560584382, 5847.09370047639, 5850.268716410449, 5896.439838215363, 5898.1152827076285, 5933.376995415462, 5954.345022047572, 5996.910046349309, 5497.39528651848, 5498.945010549884, 5520.834374968757, 5539.473749993744, 5540.085885828874, 5843.824685259449, 6100.603460775035, 6109.633421994695, 6114.663693496811, 6115.84710382244, 5780.277465316835, 5853.619514731528, 5861.648301101863, 5869.559783016291, 5875.970620146872, 5860.09168578871, 5868.09044598088, 5872.7357268923, 5872.7357268923, 5882.764689412461, 5761.049426844607, 5822.592418530747, 5833.0375488044, 5834.066549352168, 5843.970780146072, 5850.473457388855, 5905.261842269716, 5922.318933322723, 5930.555208359304, 5933.0, 4510.2783554631105, 4515.322701594721, 4516.277395616686, 4527.230370836965, 4543.5910768031035, 3956.169204467094, 3999.6600288975765, 4052.9392059119136, 4058.9188216236103, 4076.510818701775, 5626.662400255916, 5650.604457687922, 5658.292713410824, 5665.943340566541, 5672.688002159921, 5917.349091599921, 5952.0476723724, 5952.8809423812145, 5955.52355811534, 5958.731857066442, 5403.0, 5554.444555544414, 5626.099824028136, 5672.461116193955, 5675.602707810413, 5039.168537191395, 5062.594992400599, 5064.18972964324, 5065.822696205615, 5066.493350664904, 6062.272527296753, 6064.9090263646085, 6077.149199112039, 6077.604955677955, 6123.050927106306, 5724.341700704473, 5875.823725288248, 5925.970370148121, 5972.13404056418, 5982.52139828804, 6053.061775424816, 6055.273367195965, 6062.757489700539, 6068.484178844671, 6069.393060693896, 5699.715014249188, 5738.053221218619, 5750.9360768258, 5751.3673495916255, 5757.395473475225, 5868.5305175585, 5946.316173640087, 5946.405359464019, 5960.672163031075, 5988.401159883977, 5730.627509211888, 5739.655620662774, 5766.077427611562, 5773.971130144322, 5779.624324418899, 5513.42109078557, 5690.601657883908, 5754.7122643866805, 5766.394528574439, 5770.0190967536455, 5690.146478028329, 5704.543636509153, 5710.685912274865, 5711.114777209539, 5746.281714785652, 5805.303363596396, 5810.331811841692, 5812.94187058141, 5814.709264536672, 5815.0, 5501.752421140978, 5624.072028115315, 5682.659040457585, 5717.085266357363, 5723.5707321950185], "value": 5439.333362404561, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.9934400655992, 673.6564352180388, 678.6097993653648, 731.2614259597808, 752.6462562595585, 39440.40839387408, 40503.79748101259, 40605.59394406055, 40635.79682101593, 40677.38983915236, 43697.0, 43922.0, 44013.33979990295, 44126.33810492837, 44466.0, 8811.206991370775, 8974.192322690957, 8978.0, 8983.056779038205, 8986.37095403322, 8758.299336053133, 8878.600462979182, 8882.955585222066, 9124.817503649923, 9129.087091290885, 9133.634654613841, 9173.862392064124, 9186.035466276046, 9187.816243675123, 9193.678221262257, 9115.0, 9161.679341223058, 9164.175224229852, 9165.908340916572, 9166.862497062517, 9172.12864777845, 9281.90718092824, 9283.25733941283, 9295.116963888488, 9295.628174872996, 44025.0, 44152.0, 44400.77799611013, 44514.10971780546, 44584.10831783346, 3987.521497420299, 4003.8798836034966, 4065.0447144920918, 4092.3043082676018, 4279.636230920375, 33080.50379244313, 34119.82940085309, 34243.14392140191, 34401.82799086013, 35457.46813797795, 4684.789184486706, 4730.95269047312, 4731.503192164808, 4741.57325840676, 4752.120857641347, 314914.0, 315260.0, 317224.0, 318151.0, 318204.0, 64689.353106468334, 64772.35227647758, 65180.348196518375, 65220.34779652238, 65240.67379663071, 67082.64586770642, 69981.30018699748, 70907.0, 71150.0, 71291.64354178299, 19485.63600547948, 19843.0, 19858.50353741168, 19860.60278794416, 19867.403977880687, 14044.648883777783, 14154.221517816413, 14159.0, 14162.929185354007, 14163.0, 20446.488837779176, 20782.896085519475, 20834.27080052191, 20873.26943556968, 20879.0, 7311.41508679315, 7334.96332518334, 7374.188839227681, 7441.144268409203, 7652.196519365498, 1022.2639699416409, 1063.5214153630898, 1164.5691094294996, 1171.3323405658693, 1255.7551277500982, 30867.845660771553, 31602.841985789924, 32008.199795005312, 32071.358572828412, 32305.676943230268, 1230.7415442756942, 1246.8815462531045, 1250.3123282194756, 1283.1915892987358, 1295.4947570447532, 13922.303884805719, 16809.747853782203, 16993.235304411082, 17401.216945237495, 17832.643347132984, 354797.0, 361165.0, 361245.0, 361412.0, 362256.0, 58920.0, 59463.70268148631, 59491.40508594859, 59699.40300596938, 59752.40247597468, 1473.454821715972, 1515.2348064227715, 1529.258309719785, 1539.2842328317386, 1625.9349626015064, 30740.538891917087, 30986.69013309928, 32921.50617740688, 33032.50451243185, 33947.0, 53256.467435325154, 54333.0, 54363.0, 54428.18357724558, 54448.0, 353015.0, 361543.0, 361881.0, 362024.0, 654460.0, 30572.694273057874, 31061.378772424876, 31322.843385782926, 31545.0, 31589.21026974252, 45105.0, 45304.0, 45550.54449455463, 46458.767706161256, 46683.0, 39172.6082739169, 41091.589084108775, 42479.575204248795, 43152.56847431485, 43684.78157609192, 40992.0, 41513.0, 41603.16793664049, 41722.1655566893, 42301.57698422977, 41735.0, 41786.0, 42254.943626408356, 42381.36427953645, 42628.0, 36641.267174655826, 37156.44265335968, 37920.44347891161, 37934.62065379311, 38592.2281554373, 28686.713132868405, 28890.27774305575, 30495.390092198475, 30560.541591875695, 30606.0], "value": 20846.345334672722, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.18156505424105, 409.69887132957274, 421.111454830308, 421.87343796860955, 422.5520947795339, 18467.815321846778, 18883.433496995094, 19209.0, 19584.11871465782, 19599.0, 18176.36382726605, 18364.26542938281, 18477.076146192703, 19750.90124549375, 19772.802271977274, 5270.525652691257, 5654.236678048463, 5855.180274761537, 5856.297244330686, 5878.147668588073, 5507.173923911425, 5527.972360138194, 5540.750666220029, 5557.472040156199, 5561.499465048145, 5518.365387980373, 5775.364709881909, 6346.428821406095, 6352.650604216767, 6355.904661430082, 6293.9370606293805, 6300.936990630126, 6309.77915772948, 6309.842253943642, 6313.400226978452, 5438.564914806806, 5506.284183056217, 5532.972335138339, 5583.3300003999375, 5597.412271711452, 13392.598222053353, 13691.520796772065, 13708.0, 20906.5818683627, 23146.537069258688, 2410.758924107593, 2582.496413199427, 2659.787217022653, 2726.9045583404486, 2916.47503449379, 9588.520573971344, 9745.463999480096, 9914.80170396588, 9968.202543796478, 10298.330608510423, 2028.0366825757794, 2056.506438454775, 2096.297740257016, 2115.883626400547, 2168.4687251623454, 76807.0, 76839.61580192119, 79609.20390796133, 82083.0, 83391.0, 10445.477726113664, 10742.570297188182, 10758.677239682822, 10922.563097476172, 11183.496742646601, 15023.323950422257, 15200.619984500256, 15286.464973725866, 15296.235188240544, 15309.311081001382, 8185.549794761227, 8647.567621618893, 8655.13448655142, 8744.519051452231, 8818.29453643707, 8478.533680647624, 8642.049374568918, 8691.608877600524, 8798.0, 8837.0, 4713.34013238147, 4722.574968252874, 4771.73755443447, 4836.347093142451, 4868.732219727878, 1991.6415045291988, 2020.5941343048135, 2022.7067075274003, 2023.352527191314, 2027.635025695389, 440.2010351212539, 1096.703889949725, 1128.8476055732535, 1145.7536629624672, 1158.3571118029406, 9841.409515429097, 10672.466376681135, 11415.258008229439, 11429.371384573928, 11782.705432364259, 446.91508613363357, 450.92108880945705, 457.1907723329688, 460.35320374873356, 470.7975570504716, 6136.23297087864, 6242.406971337716, 6266.749330026748, 6305.054241863757, 6372.490200783926, 491170.0, 494390.0, 522790.0, 526326.0, 548221.0, 69303.65348173327, 80546.0, 83967.58016209879, 97075.0, 98952.0, 437.2151987183014, 444.7591220494848, 447.6955670144268, 447.81863345345187, 450.9301058335963, 9922.851157232792, 10283.691489255334, 10407.895921040692, 10590.576376944837, 10636.52135653882, 36694.0, 37695.2460950774, 38048.0, 38516.229675406896, 38652.420213697376, 59103.0, 59446.70276648762, 60356.39643603508, 60620.0, 61012.69493652681, 8649.091845356146, 8722.258608018135, 8755.168259015383, 8891.244244239053, 8987.370884038055, 9587.952060239653, 13449.865501345254, 13939.790903136258, 13982.510612128526, 14204.360803764062, 11028.613998510014, 11713.414329283672, 11810.76378472443, 12325.445354958869, 12394.132410731163, 11406.82889756671, 11683.357415342238, 11715.414229288332, 12002.519899203933, 12103.75792484163, 7724.841273808974, 11274.549018039515, 12308.692282693004, 12656.936715316364, 12815.359232038174, 8739.300855931637, 8930.553472326228, 8967.820643587222, 9016.09839016093, 9080.772980675536, 9219.76950576214, 12023.0, 12180.939095304468, 12433.564825231073, 12697.301648409426], "value": 8468.79502468874, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.5272660022194, 1579.8499142581454, 1587.3174534949972, 1823.507652933708, 1908.30346923373, 7725.072991241045, 8400.789980250496, 8628.784280392985, 8628.870566941501, 8629.309655227582, 8442.324614030878, 8615.43999640023, 8641.87037194441, 8643.74068777936, 8646.437981531197, 2080.1991233375106, 2324.39565712915, 2342.0982921575232, 2357.905683772647, 2377.976220237793, 2272.5682120397073, 2277.4647957730003, 2282.543491301738, 2282.6804247405375, 2287.1080278691325, 2284.657301404786, 2323.8721870297127, 2351.1535847095033, 2351.8000969917575, 2355.5759963206615, 2053.1787285085934, 2283.6117859963847, 2336.3575016870495, 2346.2257455039794, 2377.0254195779735, 2258.8080013198896, 2294.8852557372234, 2332.0322066342446, 2332.3935776698013, 2343.437574982009, 8527.74416767498, 8530.74407767768, 8574.314054875595, 8591.6992905248, 8592.5703714814, 5272.4200337963175, 5341.519263266325, 5383.515483606494, 5384.326959130109, 5384.67691938481, 3432.6567343265724, 3452.7583069185166, 3500.422430298998, 3580.838862251179, 3588.1926566522584, 1117.9217454778168, 1125.0549538387793, 1128.4696192789377, 1152.8962393384552, 1176.6940595445246, 12418.068644851583, 12429.067819913542, 12486.75026499474, 12501.93749031258, 12510.061745369134, 4535.25168347219, 4573.63410927125, 4615.469221039556, 4633.351330813689, 4647.419072615923, 10636.095931845879, 10637.787244255072, 10639.840402393971, 10650.307729997525, 10651.094656954245, 5371.086915224421, 5378.085725426687, 5378.865528361822, 5379.67721936685, 5383.757730902119, 4354.586314300136, 4749.0, 4824.6863953842885, 4837.32277481153, 4840.685355451885, 4213.115245798416, 4238.406623072772, 4275.5724427557, 4336.566343365638, 4446.444194475691, 1349.305107869448, 1387.7987691784635, 1397.2873834344355, 1398.797174409705, 1419.22652154576, 1000.2098342309639, 1006.33079002464, 1007.1791489935679, 1022.6727447216967, 1024.6772266735954, 6056.121862329937, 6056.939430605726, 6108.0, 6175.258968923668, 6207.96896015517, 429.76363000350307, 442.67684590249297, 444.69760562817595, 445.1075593435142, 447.64635937609575, 4089.20260549196, 4130.297849365615, 4165.312723400605, 4165.520965088993, 4178.91642167155, 9979.700608981742, 10125.848112278321, 10184.745381365377, 10255.9487202565, 10263.332883362558, 10058.74853128663, 10089.445080520642, 10111.393316400892, 10114.797704045877, 10118.038786315288, 7848.686052557948, 8031.196880311922, 8034.83930321402, 8068.757937261891, 8082.232187942136, 5851.795187168429, 5982.461578457785, 6081.908771368521, 6084.39156084388, 6172.351903050295, 9730.46482443444, 9833.459159746282, 10044.698659040241, 10048.849267261143, 10093.949530252301, 9374.109459601326, 9565.091316324939, 9705.514724263618, 9968.053034961667, 10006.949965250127, 7064.258252883374, 7165.749198778018, 7603.619819008917, 7751.069871615442, 7851.725189618336, 8288.461250018729, 8324.16758324187, 8360.498370097814, 8374.246317831425, 8391.832163356821, 7071.858562828817, 7331.04696389466, 7334.889976650248, 7345.522541034816, 7451.813704657427, 7155.535447046957, 7453.217412171861, 7470.775876723707, 7540.736074237376, 7565.205653406313, 7133.037039999534, 7135.714571417086, 7147.463940204401, 7168.319009694071, 7185.317394847481, 5160.380754309507, 5208.374995000624, 5217.191335343028, 5367.624266301322, 5409.918851217313, 5282.97358513205, 5287.762050707651, 5326.520613144836, 5389.56883449331, 5476.91784623223], "value": 4551.839123505102, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.91765599772054, 330.89907578188644, 332.03378169526684, 337.3994290163509, 339.6111452387016, 6127.111568822518, 6171.845703857406, 6183.288921773992, 6219.098230756537, 6219.657918814475, 6140.171076904617, 6140.81577552673, 6143.754249830002, 6156.815295541141, 6160.969195154018, 4504.977475112628, 4659.184642687533, 4731.195696731564, 4744.288356746481, 4746.454157771848, 4702.882427939295, 4734.431868175824, 4742.33607294979, 4752.405949256343, 4754.3343931849395, 4444.577765112309, 4699.0836786826585, 4701.623870090384, 4703.835365762199, 4711.363965864599, 4582.747948862811, 4684.765761711918, 4699.835505757316, 4702.52974702527, 4716.434027916672, 4123.422720819088, 4130.339145736668, 4142.482189726285, 4146.730462519927, 4155.231282212801, 5949.8810023799715, 5953.374895635982, 5955.791547295824, 5960.284765828085, 5966.194563733927, 5481.643693159972, 5483.561315094823, 5488.341399032101, 5488.780448782044, 5490.203920431555, 6352.269489008777, 6483.384078512534, 6488.578242414275, 6489.545731798776, 6492.156019717454, 4561.384213131251, 4594.816207351702, 4603.723776573416, 4604.194266003436, 4609.976950115261, 6723.428508576777, 6730.427913627299, 6752.291009444036, 6767.154105736783, 6769.661516924183, 4708.105459962596, 4708.458527269408, 4709.62323014158, 4710.52894710533, 4715.95284047162, 4773.570378665937, 4775.71345719258, 4776.689515181502, 4776.761161941889, 4781.521847815191, 5460.918086228709, 5461.290032295776, 5469.726513674301, 5474.069408200616, 5476.288082549243, 6359.332270111664, 6527.0, 6527.836804079936, 6529.2817790043055, 6536.542442029012, 1609.26778315866, 1615.4345978907288, 1617.7896873406617, 1618.0696099742695, 1633.6242664187066, 1035.3270374256792, 1085.5060947269133, 1093.5953697131954, 1106.645873320546, 1136.5567428702811, 6264.404881536247, 6329.620222786648, 6366.808995730135, 6383.2978372379, 6383.425491705676, 6649.933500664932, 6661.500387470862, 6667.433268172163, 6670.533062685662, 6675.7997260082275, 6296.5592408531875, 6298.842528936813, 6302.621842689453, 6305.558610897193, 6310.0850376695125, 3535.116220944764, 3565.714742820568, 3593.6765691087403, 3598.874039408608, 3603.117236277129, 6731.148539670318, 6911.861762764716, 6914.619695916672, 6953.548019378825, 6957.0, 6702.698378572976, 6709.865802683919, 6742.224644165885, 6752.0, 6773.085633439521, 6907.7236910524025, 6914.204866440323, 6915.861682766316, 6917.412019978358, 6919.4464442844455, 6705.362990515893, 6709.664516774239, 6712.093867327848, 6714.227863795726, 6720.294369091371, 6495.935040649533, 6570.0, 6764.053032575345, 6772.424343930624, 6776.661166941732, 7008.369246767816, 7035.613041282779, 7045.365917067488, 7051.471139664648, 7061.788146355618, 4528.2075636762775, 4560.657950653648, 4564.88587785308, 4573.748443835621, 4576.832907608538, 6767.323267673193, 6771.322867713189, 6772.88247439167, 6776.017477465642, 6776.017477465838, 6394.328595497405, 6565.048068030204, 6578.407943285128, 6579.0, 6579.605223686594, 6535.411812936859, 6557.278699343164, 6558.934410655832, 6561.671916404065, 6562.3109573496, 6403.295637479967, 6441.452476539546, 6442.452391546771, 6445.6454894981225, 6465.0, 6581.967090164519, 6584.8024559263295, 6608.4052435281055, 6619.271880093283, 6681.09805176295, 6097.817065488042, 6106.480949119375, 6146.354632763496, 6478.060681201294, 6481.708323125565], "value": 4917.471667090119, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.3643193989288, 733.3912852332564, 733.8458923626039, 759.8176437654963, 826.883706995556, 13707.971902107332, 13794.31028448576, 13824.654383640414, 13831.654208644788, 13910.582682519527, 12315.261084334947, 12480.313582752944, 12556.81164782529, 13596.116252443584, 13604.183748975069, 890.799570096728, 900.0549423105713, 908.0193391137556, 908.8091500784843, 909.0863681999581, 895.2837729816154, 900.5677274908015, 900.6982660808604, 901.0133903375826, 901.432097778402, 932.3753085432776, 932.8367535681263, 933.12752576341, 934.0052843721448, 934.6588495199237, 929.1498279074639, 930.3952430919923, 930.6510058727977, 930.702175303903, 930.9813803723888, 668.6656671664168, 673.3367632881608, 675.5710124071201, 675.9560628559128, 677.0555075669463, 12460.937695311555, 12481.75036499274, 12492.188007779465, 12512.937435312766, 12544.937275313656, 11732.413379331, 11967.461464234044, 12313.97405074616, 12454.875451245462, 12721.363931803466, 10498.580056797718, 10695.78608427827, 10702.197335199891, 10792.298500597515, 10839.18706097038, 3737.1030952571186, 3738.2897249522503, 3792.241551689673, 3792.5259342582176, 3903.5315762108444, 46623.5337646626, 54246.45753542453, 54786.45213547893, 54847.72576137134, 57165.714171429296, 11279.661610151708, 11426.828597571042, 11584.42077896102, 11585.590062111802, 11817.704557385961, 19277.51806204828, 19293.228270869287, 19324.516887077934, 19339.12973916177, 19346.129424175946, 8474.0, 8513.063563008189, 8581.570921453902, 8611.741647750578, 8822.911770882209, 4702.083093796745, 4936.580390666833, 4963.726995015309, 5033.546980771748, 5033.672811267256, 3426.8971930842113, 3456.2396272819938, 3479.878204262889, 3485.5643044619424, 3488.860445582199, 610.5664977865765, 619.8884200843801, 622.6015350175891, 626.4831514000967, 628.1959092361784, 703.0298188499837, 749.2769477454283, 761.5735188294573, 786.9960650196713, 821.0311832038252, 9760.267979901535, 9838.803223935482, 9861.852072218922, 9935.602575897028, 9956.0, 681.6148875885109, 721.6572128239045, 763.5723994562951, 765.8927750114989, 869.9391042626955, 4517.322401639714, 4539.500654927955, 4688.249880019181, 4714.22215334466, 4786.2820576913045, 64786.35213647898, 66214.66892665505, 66344.66827665927, 66686.0, 66714.66642666853, 55440.16839747407, 57530.0, 58291.708541457025, 59121.40878591245, 59844.70077649584, 53558.0, 53881.1917821233, 53947.46052539424, 54890.45109548853, 55515.0, 9315.02192269829, 9379.859302110337, 9405.905940940504, 9487.193588544775, 9543.42739435636, 37017.25965480729, 39267.80366098265, 39952.60047399605, 39958.60041399549, 40583.0, 42463.57536424596, 45365.0, 49379.50620493749, 49913.251301231234, 50237.74881125571, 11289.943550282525, 11506.137039721887, 11710.882891170979, 11804.468798904241, 12110.576129835414, 11540.538378464767, 12369.62891113268, 12385.504579816707, 12508.437120329425, 12621.05342099328, 11346.773064538827, 11617.0, 12017.579384721494, 12030.338331391857, 12179.756404871676, 11090.889091108986, 11153.107751379708, 11247.550097996316, 11365.204435689422, 11511.30932144074, 11064.834027489755, 11429.657110286706, 11431.256968297033, 11529.423528823692, 12066.879331206575, 9871.555779989774, 9928.468670076518, 10103.808987476106, 10398.410913576292, 10476.266661333633, 10189.5004620749, 10605.478399297384, 10613.787724245318, 10679.733006674895, 10794.298370605886], "value": 6816.1428930245, "unit": "score"}], "name": "dromaeo_css", "value": 7260.795393171917, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 111.0, 111.0, 111.0, 111.0, 107.0, 109.0, 111.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 124.0, 155.0, 178.0, 138.0, 163.0, 158.0, 134.0, 189.0, 127.0], "value": 152.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 161.0, 161.0, 161.0, 162.0, 160.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 77.0, 179.0, 77.0, 74.0, 77.0, 75.0, 170.0, 76.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 148.0, 94.0, 93.0, 92.0, 145.0, 93.0, 92.0, 142.0], "value": 108.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 147.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 86.0, 53.0, 53.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 57.0, 58.0, 58.0, 58.0, 59.0, 59.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 71.0, 73.0, 73.0, 73.0, 80.0, 72.0, 74.0, 80.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 127.0, 112.0, 113.0, 110.0, 111.0, 114.0, 116.0, 161.0, 120.0], "value": 120.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 153.0, 155.0, 157.0, 161.0, 160.0, 156.0, 158.0, 159.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 67.0, 66.0, 64.0, 66.0, 67.0, 66.0, 66.0, 62.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1519.4, "alertThreshold": 2.0}]} 18:51:05 INFO - Return code: 0 18:51:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:51:05 ERROR - # TBPL SUCCESS # 18:51:05 INFO - Running post-action listener: _resource_record_post_action 18:51:05 INFO - Running post-run listener: _resource_record_post_run 18:51:06 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 499884032; Read time: 464; Write time: 502968 18:51:06 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 249204736; Read time: 124; Write time: 300588 18:51:06 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 250679296; Read time: 340; Write time: 202380 18:51:06 INFO - Running post-run listener: _upload_blobber_files 18:51:06 INFO - Blob upload gear active. 18:51:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:51:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:51:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:51:06 INFO - (blobuploader) - INFO - Open directory for files ... 18:51:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:51:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:07 INFO - (blobuploader) - INFO - Done attempting. 18:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:08 INFO - (blobuploader) - INFO - Done attempting. 18:51:08 INFO - (blobuploader) - INFO - Iteration through files over. 18:51:08 INFO - Return code: 0 18:51:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:51:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:51:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d457af2f7383016834cda7db5d86d11ffe2497440c96bda361968efe0ad72fb1ed657b9a9e8acb03dcaec9a5fe554ee0d2a8bf06f330721b0557aa9f6d99c73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58f10f737cc55544cdcf482078833a1a34b22afe13c165e4909bf060dbae007a7a912a53749cf31409a6ded0334be38cf2e8c1af2482c191a2fad5103f270d4"} 18:51:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:51:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:51:08 INFO - Contents: 18:51:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d457af2f7383016834cda7db5d86d11ffe2497440c96bda361968efe0ad72fb1ed657b9a9e8acb03dcaec9a5fe554ee0d2a8bf06f330721b0557aa9f6d99c73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58f10f737cc55544cdcf482078833a1a34b22afe13c165e4909bf060dbae007a7a912a53749cf31409a6ded0334be38cf2e8c1af2482c191a2fad5103f270d4"} 18:51:08 INFO - Running post-run listener: copy_logs_to_upload_dir 18:51:08 INFO - Copying logs to upload dir... 18:51:08 INFO - mkdir: /builds/slave/test/build/upload/logs 18:51:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.419795 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-06-03 18:51:07.886159) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 18:51:07.889931) ========= 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=5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d457af2f7383016834cda7db5d86d11ffe2497440c96bda361968efe0ad72fb1ed657b9a9e8acb03dcaec9a5fe554ee0d2a8bf06f330721b0557aa9f6d99c73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58f10f737cc55544cdcf482078833a1a34b22afe13c165e4909bf060dbae007a7a912a53749cf31409a6ded0334be38cf2e8c1af2482c191a2fad5103f270d4"} build_url:https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/GR7QdTSKSDuqQWRayX9Wcw/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d457af2f7383016834cda7db5d86d11ffe2497440c96bda361968efe0ad72fb1ed657b9a9e8acb03dcaec9a5fe554ee0d2a8bf06f330721b0557aa9f6d99c73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58f10f737cc55544cdcf482078833a1a34b22afe13c165e4909bf060dbae007a7a912a53749cf31409a6ded0334be38cf2e8c1af2482c191a2fad5103f270d4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 18:51:07.915546) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 18:51:07.915869) ========= 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=5bd038846534b2fdfa68eeae000001e2-1465000945.985505-1188041228 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004413 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 18:51:07.999243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 18:51:07.999565) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 18:51:07.999851) ========= ========= Total master_lag: 0.16 =========