builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1470650488.38 results: success (0) buildid: 20160808000852 builduid: 3b293a11bb944833a30207ae4f970037 revision: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.381083) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.381549) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.381885) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.411831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.412112) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-08 03:01:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.0M=0s 2016-08-08 03:01:30 (41.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.530903) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.531216) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021996 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.586918) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:28.587288) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 --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 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 --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=9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-08 03:01:30,309 truncating revision to first 12 chars 2016-08-08 03:01:30,309 Setting DEBUG logging. 2016-08-08 03:01:30,309 attempt 1/10 2016-08-08 03:01:30,309 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/93a54e1c76c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 03:01:30,620 unpacking tar archive at: mozilla-aurora-93a54e1c76c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.726034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:29.331783) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:29.332089) ========= script_repo_revision: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:29.332540) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:29.332836) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 03:01:29.351768) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-08 03:01:29.352055) ========= /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=9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:01:31 INFO - MultiFileLogger online at 20160808 03:01:31 in /builds/slave/test 03:01:31 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 03:01:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:01:31 INFO - {'append_to_log': False, 03:01:31 INFO - 'base_work_dir': '/builds/slave/test', 03:01:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:01:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:01:31 INFO - 'branch': 'Mozilla-Aurora', 03:01:31 INFO - 'buildbot_json_path': 'buildprops.json', 03:01:31 INFO - 'config_files': ('talos/linux_config.py',), 03:01:31 INFO - 'default_actions': ('clobber', 03:01:31 INFO - 'read-buildbot-config', 03:01:31 INFO - 'download-and-extract', 03:01:31 INFO - 'populate-webroot', 03:01:31 INFO - 'create-virtualenv', 03:01:31 INFO - 'install', 03:01:31 INFO - 'run-tests'), 03:01:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:01:31 INFO - 'download_minidump_stackwalk': True, 03:01:31 INFO - 'download_symbols': 'ondemand', 03:01:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:01:31 INFO - 'tooltool.py': '/tools/tooltool.py', 03:01:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:01:31 INFO - '/tools/misc-python/virtualenv.py')}, 03:01:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:01:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:01:31 INFO - 'installer_path': 'installer.exe', 03:01:31 INFO - 'log_level': 'info', 03:01:31 INFO - 'log_name': 'talos', 03:01:31 INFO - 'log_to_console': True, 03:01:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:01:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:01:31 INFO - 'opt_config_files': (), 03:01:31 INFO - 'pip_index': False, 03:01:31 INFO - 'sps_profile': False, 03:01:31 INFO - 'sps_profile_interval': 0, 03:01:31 INFO - 'suite': 'dromaeojs', 03:01:31 INFO - 'system_bits': '32', 03:01:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:01:31 INFO - 'title': 'talos-linux64-ix-029', 03:01:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:01:31 INFO - 'use_talos_json': True, 03:01:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:01:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:01:31 INFO - 'work_dir': 'build'} 03:01:31 INFO - [mozharness: 2016-08-08 10:01:31.176216Z] Running clobber step. 03:01:31 INFO - Running pre-action listener: _resource_record_pre_action 03:01:31 INFO - Running main action method: clobber 03:01:31 INFO - rmtree: /builds/slave/test/build 03:01:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:01:31 INFO - Running post-action listener: _resource_record_post_action 03:01:31 INFO - [mozharness: 2016-08-08 10:01:31.646148Z] Finished clobber step (success) 03:01:31 INFO - [mozharness: 2016-08-08 10:01:31.646251Z] Running read-buildbot-config step. 03:01:31 INFO - Running pre-action listener: _resource_record_pre_action 03:01:31 INFO - Running main action method: read_buildbot_config 03:01:31 INFO - Using buildbot properties: 03:01:31 INFO - { 03:01:31 INFO - "project": "", 03:01:31 INFO - "product": "firefox", 03:01:31 INFO - "script_repo_revision": "production", 03:01:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:01:31 INFO - "repository": "", 03:01:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:01:31 INFO - "buildid": "20160808000852", 03:01:31 INFO - "pgo_build": "True", 03:01:31 INFO - "basedir": "/builds/slave/test", 03:01:31 INFO - "buildnumber": 664, 03:01:31 INFO - "slavename": "talos-linux64-ix-029", 03:01:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:01:31 INFO - "platform": "ubuntu64_hw", 03:01:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:01:31 INFO - "branch": "mozilla-aurora", 03:01:31 INFO - "repo_path": "releases/mozilla-aurora", 03:01:31 INFO - "revision": "93a54e1c76c1602c65d2ba7bd1b6f20a997765e5", 03:01:31 INFO - "stage_platform": "linux64-pgo", 03:01:31 INFO - "builduid": "3b293a11bb944833a30207ae4f970037", 03:01:31 INFO - "slavebuilddir": "test" 03:01:31 INFO - } 03:01:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:01:31 INFO - Running post-action listener: _resource_record_post_action 03:01:31 INFO - [mozharness: 2016-08-08 10:01:31.649179Z] Finished read-buildbot-config step (success) 03:01:31 INFO - [mozharness: 2016-08-08 10:01:31.649269Z] Running download-and-extract step. 03:01:31 INFO - Running pre-action listener: _resource_record_pre_action 03:01:31 INFO - Running main action method: download_and_extract 03:01:31 INFO - mkdir: /builds/slave/test/build/tests 03:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:31 INFO - https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:01:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:01:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:01:31 INFO - retry: Failed, sleeping 30 seconds before retrying 03:02:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:02:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:02:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:02:01 INFO - retry: Failed, sleeping 60 seconds before retrying 03:03:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:03:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:03:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:03:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:03:01 INFO - Caught exception: HTTP Error 404: Not Found 03:03:01 INFO - Caught exception: HTTP Error 404: Not Found 03:03:01 INFO - Caught exception: HTTP Error 404: Not Found 03:03:01 INFO - trying https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:03:01 INFO - Downloading https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:03:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:03:02 INFO - Downloaded 1361 bytes. 03:03:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:03:02 INFO - Using the following test package requirements: 03:03:02 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:03:02 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:03:02 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:03:02 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'jsshell-linux-x86_64.zip'], 03:03:02 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:03:02 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:03:02 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:03:02 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:03:02 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:03:02 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:03:02 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:03:02 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:02 INFO - https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:03:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:03:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:03:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:03:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:03:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:03:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:03:02 INFO - retry: Failed, sleeping 30 seconds before retrying 03:03:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:03:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:03:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:03:32 INFO - retry: Failed, sleeping 60 seconds before retrying 03:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:04:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:04:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:04:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:04:32 INFO - Caught exception: HTTP Error 404: Not Found 03:04:32 INFO - Caught exception: HTTP Error 404: Not Found 03:04:32 INFO - Caught exception: HTTP Error 404: Not Found 03:04:32 INFO - trying https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:04:32 INFO - Downloading https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:04:33 INFO - Downloaded 21766427 bytes. 03:04:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:04:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:04:34 INFO - Return code: 0 03:04:34 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:34 INFO - https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:04:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:04:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:04:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:04:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:04:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:04:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:04:34 INFO - retry: Failed, sleeping 30 seconds before retrying 03:05:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:05:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:05:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:05:04 INFO - retry: Failed, sleeping 60 seconds before retrying 03:06:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:06:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:06:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:06:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:06:04 INFO - Caught exception: HTTP Error 404: Not Found 03:06:04 INFO - Caught exception: HTTP Error 404: Not Found 03:06:04 INFO - Caught exception: HTTP Error 404: Not Found 03:06:04 INFO - trying https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:06:04 INFO - Downloading https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:06:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:06:05 INFO - Downloaded 21766427 bytes. 03:06:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:06:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:06:06 INFO - Return code: 0 03:06:06 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')]} 03:06:06 INFO - https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:06:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:06:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:06:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:06:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:06:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:06:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:06:06 INFO - retry: Failed, sleeping 30 seconds before retrying 03:06:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:06:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:06:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:06:37 INFO - retry: Failed, sleeping 60 seconds before retrying 03:07:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:07:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:07:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:07:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:07:37 INFO - Caught exception: HTTP Error 404: Not Found 03:07:37 INFO - Caught exception: HTTP Error 404: Not Found 03:07:37 INFO - Caught exception: HTTP Error 404: Not Found 03:07:37 INFO - trying https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:07:37 INFO - Downloading https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:07:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:07:37 INFO - Downloaded 14413723 bytes. 03:07:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:07:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:07:38 INFO - Return code: 0 03:07: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')]} 03:07:38 INFO - https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:07:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:07:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:07:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:07:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:07:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:07:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:07:38 INFO - retry: Failed, sleeping 30 seconds before retrying 03:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:08:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:08:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:08:08 INFO - retry: Failed, sleeping 60 seconds before retrying 03:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:09:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:09:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:09:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:09:08 INFO - Caught exception: HTTP Error 404: Not Found 03:09:08 INFO - Caught exception: HTTP Error 404: Not Found 03:09:08 INFO - Caught exception: HTTP Error 404: Not Found 03:09:08 INFO - trying https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:09:08 INFO - Downloading https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:09:10 INFO - Downloaded 63755116 bytes. 03:09:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:09:10 INFO - mkdir: /builds/slave/test/properties 03:09:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:09:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:09:10 INFO - Contents: 03:09:10 INFO - build_url:https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:09:10 INFO - Running post-action listener: _resource_record_post_action 03:09:10 INFO - Running post-action listener: set_extra_try_arguments 03:09:10 INFO - [mozharness: 2016-08-08 10:09:10.797017Z] Finished download-and-extract step (success) 03:09:10 INFO - [mozharness: 2016-08-08 10:09:10.797249Z] Running populate-webroot step. 03:09:10 INFO - Running pre-action listener: _resource_record_pre_action 03:09:10 INFO - Running main action method: populate_webroot 03:09:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:09:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:09:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:09:10 INFO - '--tppagecycles', 03:09:10 INFO - '7'], 03:09:10 INFO - 'tests': ['tsvgm']}}, 03:09:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:09:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:09:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:09:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:09:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['damp', 'tps']}, 03:09:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'tests': ['damp', 'tps']}, 03:09:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['dromaeo_dom']}, 03:09:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:09:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['basic_compositor_video']}, 03:09:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:09:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other-e10s': {'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tsvgx', 03:09:10 INFO - 'tsvgr_opacity', 03:09:10 INFO - 'tart', 03:09:10 INFO - 'tscrollx', 03:09:10 INFO - 'cart']}, 03:09:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:09:10 INFO - 'tsvgr_opacity', 03:09:10 INFO - 'tart', 03:09:10 INFO - 'tscrollx', 03:09:10 INFO - 'cart']}, 03:09:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tp5o']}, 03:09:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'tests': ['tp5o']}, 03:09:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s', 03:09:10 INFO - '--xperf_path', 03:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:10 INFO - 'tests': ['tp5n']}, 03:09:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--xperf_path', 03:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:10 INFO - 'tests': ['tp5n']}}, 03:09:10 INFO - 'talos.zip': {'path': '', 03:09:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:09:10 INFO - Running post-action listener: _resource_record_post_action 03:09:10 INFO - [mozharness: 2016-08-08 10:09:10.819493Z] Finished populate-webroot step (success) 03:09:10 INFO - [mozharness: 2016-08-08 10:09:10.819582Z] Running create-virtualenv step. 03:09:10 INFO - Running pre-action listener: _resource_record_pre_action 03:09:10 INFO - Running main action method: create_virtualenv 03:09:10 INFO - Creating virtualenv /builds/slave/test/build/venv 03:09:10 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 03:09:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:09:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:09:10 INFO - Using real prefix '/usr' 03:09:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:09:11 INFO - Installing distribute.............................................................................................................................................................................................done. 03:09:13 INFO - Installing pip.................done. 03:09:13 INFO - Return code: 0 03:09:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:13 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 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:13 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 03:09:13 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 03:09:13 INFO - Using env: {'DISPLAY': ':0', 03:09:13 INFO - 'HOME': '/home/cltbld', 03:09:13 INFO - 'LANG': 'en_US.UTF-8', 03:09:13 INFO - 'LANGUAGE': 'en_US:en', 03:09:13 INFO - 'LOGNAME': 'cltbld', 03:09:13 INFO - 'MAIL': '/var/mail/cltbld', 03:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:13 INFO - 'MOZ_NO_REMOTE': '1', 03:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:13 INFO - 'NO_EM_RESTART': '1', 03:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:13 INFO - 'PWD': '/builds/slave/test', 03:09:13 INFO - 'SHELL': '/bin/bash', 03:09:13 INFO - 'SHLVL': '1', 03:09:13 INFO - 'TERM': 'linux', 03:09:13 INFO - 'TMOUT': '86400', 03:09:13 INFO - 'USER': 'cltbld', 03:09:13 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:13 INFO - '_': '/tools/buildbot/bin/python'} 03:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:13 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 03:09:13 INFO - Cleaning up... 03:09:13 INFO - Return code: 0 03:09:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:13 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:13 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>=3.1.1'] in /builds/slave/test/build 03:09:13 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>=3.1.1 03:09:13 INFO - Using env: {'DISPLAY': ':0', 03:09:13 INFO - 'HOME': '/home/cltbld', 03:09:13 INFO - 'LANG': 'en_US.UTF-8', 03:09:13 INFO - 'LANGUAGE': 'en_US:en', 03:09:13 INFO - 'LOGNAME': 'cltbld', 03:09:13 INFO - 'MAIL': '/var/mail/cltbld', 03:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:13 INFO - 'MOZ_NO_REMOTE': '1', 03:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:13 INFO - 'NO_EM_RESTART': '1', 03:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:13 INFO - 'PWD': '/builds/slave/test', 03:09:13 INFO - 'SHELL': '/bin/bash', 03:09:13 INFO - 'SHLVL': '1', 03:09:13 INFO - 'TERM': 'linux', 03:09:13 INFO - 'TMOUT': '86400', 03:09:13 INFO - 'USER': 'cltbld', 03:09:13 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:13 INFO - '_': '/tools/buildbot/bin/python'} 03:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:13 INFO - Downloading/unpacking psutil>=3.1.1 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:15 INFO - Installing collected packages: psutil 03:09:15 INFO - Running setup.py install for psutil 03:09:16 INFO - building 'psutil._psutil_linux' extension 03:09:16 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 03:09:16 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 03:09:16 INFO - building 'psutil._psutil_posix' extension 03:09:16 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 03:09:16 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 03:09:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:16 INFO - Successfully installed psutil 03:09:16 INFO - Cleaning up... 03:09:16 INFO - Return code: 0 03:09:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:16 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:16 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.3'] in /builds/slave/test/build 03:09:16 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.3 03:09:16 INFO - Using env: {'DISPLAY': ':0', 03:09:16 INFO - 'HOME': '/home/cltbld', 03:09:16 INFO - 'LANG': 'en_US.UTF-8', 03:09:16 INFO - 'LANGUAGE': 'en_US:en', 03:09:16 INFO - 'LOGNAME': 'cltbld', 03:09:16 INFO - 'MAIL': '/var/mail/cltbld', 03:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:16 INFO - 'MOZ_NO_REMOTE': '1', 03:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:16 INFO - 'NO_EM_RESTART': '1', 03:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:16 INFO - 'PWD': '/builds/slave/test', 03:09:16 INFO - 'SHELL': '/bin/bash', 03:09:16 INFO - 'SHLVL': '1', 03:09:16 INFO - 'TERM': 'linux', 03:09:16 INFO - 'TMOUT': '86400', 03:09:16 INFO - 'USER': 'cltbld', 03:09:16 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:16 INFO - '_': '/tools/buildbot/bin/python'} 03:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:09:19 INFO - Installing collected packages: mozsystemmonitor 03:09:19 INFO - Running setup.py install for mozsystemmonitor 03:09:19 INFO - Successfully installed mozsystemmonitor 03:09:19 INFO - Cleaning up... 03:09:19 INFO - Return code: 0 03:09:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:19 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:19 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==2.5.1'] in /builds/slave/test/build 03:09:19 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==2.5.1 03:09:19 INFO - Using env: {'DISPLAY': ':0', 03:09:19 INFO - 'HOME': '/home/cltbld', 03:09:19 INFO - 'LANG': 'en_US.UTF-8', 03:09:19 INFO - 'LANGUAGE': 'en_US:en', 03:09:19 INFO - 'LOGNAME': 'cltbld', 03:09:19 INFO - 'MAIL': '/var/mail/cltbld', 03:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:19 INFO - 'MOZ_NO_REMOTE': '1', 03:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:19 INFO - 'NO_EM_RESTART': '1', 03:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:19 INFO - 'PWD': '/builds/slave/test', 03:09:19 INFO - 'SHELL': '/bin/bash', 03:09:19 INFO - 'SHLVL': '1', 03:09:19 INFO - 'TERM': 'linux', 03:09:19 INFO - 'TMOUT': '86400', 03:09:19 INFO - 'USER': 'cltbld', 03:09:19 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:19 INFO - '_': '/tools/buildbot/bin/python'} 03:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:19 INFO - Downloading/unpacking jsonschema==2.5.1 03:09:19 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 03:09:19 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 03:09:19 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 03:09:19 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 03:09:19 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 03:09:19 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 03:09:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:09:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 INFO - Downloading functools32-3.2.3-2.tar.gz 03:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:09:22 INFO - warning: no files found matching '*.txt' 03:09:22 INFO - no previously-included directories found matching 'build' 03:09:22 INFO - no previously-included directories found matching 'dist' 03:09:22 INFO - no previously-included directories found matching '.git*' 03:09:22 INFO - Installing collected packages: jsonschema, functools32 03:09:22 INFO - Running setup.py install for functools32 03:09:22 INFO - warning: no files found matching '*.txt' 03:09:22 INFO - no previously-included directories found matching 'build' 03:09:22 INFO - no previously-included directories found matching 'dist' 03:09:22 INFO - no previously-included directories found matching '.git*' 03:09:22 INFO - Successfully installed jsonschema functools32 03:09:22 INFO - Cleaning up... 03:09:22 INFO - Return code: 0 03:09:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:09:22 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')]} 03:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:22 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')]} 03:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:22 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:22 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:09:22 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 functools32==3.2.3-2 03:09:22 INFO - Using env: {'DISPLAY': ':0', 03:09:22 INFO - 'HOME': '/home/cltbld', 03:09:22 INFO - 'LANG': 'en_US.UTF-8', 03:09:22 INFO - 'LANGUAGE': 'en_US:en', 03:09:22 INFO - 'LOGNAME': 'cltbld', 03:09:22 INFO - 'MAIL': '/var/mail/cltbld', 03:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:22 INFO - 'MOZ_NO_REMOTE': '1', 03:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:22 INFO - 'NO_EM_RESTART': '1', 03:09:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:22 INFO - 'PWD': '/builds/slave/test', 03:09:22 INFO - 'SHELL': '/bin/bash', 03:09:22 INFO - 'SHLVL': '1', 03:09:22 INFO - 'TERM': 'linux', 03:09:22 INFO - 'TMOUT': '86400', 03:09:22 INFO - 'USER': 'cltbld', 03:09:22 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:22 INFO - '_': '/tools/buildbot/bin/python'} 03:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:09:22 INFO - Cleaning up... 03:09:22 INFO - Return code: 0 03:09:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:09:22 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')]} 03:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:22 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')]} 03:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:22 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 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:22 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 03:09:22 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 03:09:22 INFO - Using env: {'DISPLAY': ':0', 03:09:22 INFO - 'HOME': '/home/cltbld', 03:09:22 INFO - 'LANG': 'en_US.UTF-8', 03:09:22 INFO - 'LANGUAGE': 'en_US:en', 03:09:22 INFO - 'LOGNAME': 'cltbld', 03:09:22 INFO - 'MAIL': '/var/mail/cltbld', 03:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:22 INFO - 'MOZ_NO_REMOTE': '1', 03:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:22 INFO - 'NO_EM_RESTART': '1', 03:09:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:22 INFO - 'PWD': '/builds/slave/test', 03:09:22 INFO - 'SHELL': '/bin/bash', 03:09:22 INFO - 'SHLVL': '1', 03:09:22 INFO - 'TERM': 'linux', 03:09:22 INFO - 'TMOUT': '86400', 03:09:22 INFO - 'USER': 'cltbld', 03:09:22 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:22 INFO - '_': '/tools/buildbot/bin/python'} 03:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:22 INFO - Downloading/unpacking blobuploader==1.2.4 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:24 INFO - Downloading blobuploader-1.2.4.tar.gz 03:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:09:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:09:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:25 INFO - Downloading docopt-0.6.1.tar.gz 03:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:09:25 INFO - Installing collected packages: blobuploader, requests, docopt 03:09:25 INFO - Running setup.py install for blobuploader 03:09:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:09:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:09:25 INFO - Running setup.py install for requests 03:09:26 INFO - Running setup.py install for docopt 03:09:26 INFO - Successfully installed blobuploader requests docopt 03:09:26 INFO - Cleaning up... 03:09:26 INFO - Return code: 0 03:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:26 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 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:26 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 03:09:26 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 03:09:26 INFO - Using env: {'DISPLAY': ':0', 03:09:26 INFO - 'HOME': '/home/cltbld', 03:09:26 INFO - 'LANG': 'en_US.UTF-8', 03:09:26 INFO - 'LANGUAGE': 'en_US:en', 03:09:26 INFO - 'LOGNAME': 'cltbld', 03:09:26 INFO - 'MAIL': '/var/mail/cltbld', 03:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:26 INFO - 'MOZ_NO_REMOTE': '1', 03:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:26 INFO - 'NO_EM_RESTART': '1', 03:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:26 INFO - 'PWD': '/builds/slave/test', 03:09:26 INFO - 'SHELL': '/bin/bash', 03:09:26 INFO - 'SHLVL': '1', 03:09:26 INFO - 'TERM': 'linux', 03:09:26 INFO - 'TMOUT': '86400', 03:09:26 INFO - 'USER': 'cltbld', 03:09:26 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:26 INFO - '_': '/tools/buildbot/bin/python'} 03:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:26 INFO - Running setup.py (path:/tmp/pip-AwnVDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:26 INFO - Running setup.py (path:/tmp/pip-W1tC5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:26 INFO - Running setup.py (path:/tmp/pip-gkTQAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:26 INFO - Running setup.py (path:/tmp/pip-KaTijh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:26 INFO - Running setup.py (path:/tmp/pip-hOK9VA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:26 INFO - Running setup.py (path:/tmp/pip-pOEqz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:26 INFO - Running setup.py (path:/tmp/pip-Hn39fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:26 INFO - Running setup.py (path:/tmp/pip-2_tHoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:27 INFO - Running setup.py (path:/tmp/pip-HHgUx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:27 INFO - Running setup.py (path:/tmp/pip-DfCIhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:27 INFO - Running setup.py (path:/tmp/pip-i9hN6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:27 INFO - Running setup.py (path:/tmp/pip-pY42rU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:27 INFO - Running setup.py (path:/tmp/pip-rZRbqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:27 INFO - Running setup.py (path:/tmp/pip-nc14x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:27 INFO - Running setup.py (path:/tmp/pip-QaJCWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:27 INFO - Running setup.py (path:/tmp/pip-QbLHQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:27 INFO - Running setup.py (path:/tmp/pip-2Wgz0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:09:27 INFO - Running setup.py install for manifestparser 03:09:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Running setup.py install for mozcrash 03:09:27 INFO - Running setup.py install for mozdebug 03:09:27 INFO - Running setup.py install for mozdevice 03:09:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Running setup.py install for mozfile 03:09:28 INFO - Running setup.py install for mozhttpd 03:09:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Running setup.py install for mozinfo 03:09:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Running setup.py install for mozInstall 03:09:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Running setup.py install for mozleak 03:09:28 INFO - Running setup.py install for mozlog 03:09:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Running setup.py install for moznetwork 03:09:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Running setup.py install for mozprocess 03:09:28 INFO - Running setup.py install for mozprofile 03:09:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:09:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:09:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:09:29 INFO - Running setup.py install for mozrunner 03:09:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:09:29 INFO - Running setup.py install for mozscreenshot 03:09:29 INFO - Running setup.py install for moztest 03:09:29 INFO - Running setup.py install for mozversion 03:09:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:09:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:09:29 INFO - Cleaning up... 03:09:29 INFO - Return code: 0 03:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:29 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 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:29 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 03:09:29 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 03:09:29 INFO - Using env: {'DISPLAY': ':0', 03:09:29 INFO - 'HOME': '/home/cltbld', 03:09:29 INFO - 'LANG': 'en_US.UTF-8', 03:09:29 INFO - 'LANGUAGE': 'en_US:en', 03:09:29 INFO - 'LOGNAME': 'cltbld', 03:09:29 INFO - 'MAIL': '/var/mail/cltbld', 03:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:29 INFO - 'MOZ_NO_REMOTE': '1', 03:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:29 INFO - 'NO_EM_RESTART': '1', 03:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:29 INFO - 'PWD': '/builds/slave/test', 03:09:29 INFO - 'SHELL': '/bin/bash', 03:09:29 INFO - 'SHLVL': '1', 03:09:29 INFO - 'TERM': 'linux', 03:09:29 INFO - 'TMOUT': '86400', 03:09:29 INFO - 'USER': 'cltbld', 03:09:29 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:29 INFO - '_': '/tools/buildbot/bin/python'} 03:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:29 INFO - Running setup.py (path:/tmp/pip-QKWHUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:29 INFO - Running setup.py (path:/tmp/pip-8otmPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:29 INFO - Running setup.py (path:/tmp/pip-6zPD1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:29 INFO - Running setup.py (path:/tmp/pip-aeWEzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:30 INFO - Running setup.py (path:/tmp/pip-J7YMR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:30 INFO - Running setup.py (path:/tmp/pip-iNDiEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:30 INFO - Running setup.py (path:/tmp/pip-HO2AFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:30 INFO - Running setup.py (path:/tmp/pip-MbbRQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:30 INFO - Running setup.py (path:/tmp/pip-KYjClq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:30 INFO - Running setup.py (path:/tmp/pip-LHOtH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:30 INFO - Running setup.py (path:/tmp/pip-fZnq_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:30 INFO - Running setup.py (path:/tmp/pip-08tZVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:30 INFO - Running setup.py (path:/tmp/pip-EFLCwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:30 INFO - Running setup.py (path:/tmp/pip-rYTOjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:30 INFO - Running setup.py (path:/tmp/pip-xoMiuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:30 INFO - Running setup.py (path:/tmp/pip-akfRmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:30 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)) 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:30 INFO - Running setup.py (path:/tmp/pip-uTMQBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:30 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)) 03:09:30 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)) 03:09:30 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)) 03:09:30 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)) 03:09:30 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)) 03:09:30 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)) 03:09:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09:30 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 03:09:30 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 03:09:30 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 03:09:30 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 03:09:30 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 03:09:30 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 03:09:32 INFO - Downloading blessings-1.6.tar.gz 03:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:09:32 INFO - Installing collected packages: blessings 03:09:32 INFO - Running setup.py install for blessings 03:09:33 INFO - Successfully installed blessings 03:09:33 INFO - Cleaning up... 03:09:33 INFO - Return code: 0 03:09:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:33 INFO - Reading from file tmpfile_stdout 03:09:33 INFO - Current package versions: 03:09:33 INFO - argparse == 1.2.1 03:09:33 INFO - blessings == 1.6 03:09:33 INFO - blobuploader == 1.2.4 03:09:33 INFO - docopt == 0.6.1 03:09:33 INFO - functools32 == 3.2.3-2 03:09:33 INFO - jsonschema == 2.5.1 03:09:33 INFO - manifestparser == 1.1 03:09:33 INFO - mozInstall == 1.12 03:09:33 INFO - mozcrash == 0.17 03:09:33 INFO - mozdebug == 0.1 03:09:33 INFO - mozdevice == 0.48 03:09:33 INFO - mozfile == 1.2 03:09:33 INFO - mozhttpd == 0.7 03:09:33 INFO - mozinfo == 0.9 03:09:33 INFO - mozleak == 0.1 03:09:33 INFO - mozlog == 3.3 03:09:33 INFO - moznetwork == 0.27 03:09:33 INFO - mozprocess == 0.23 03:09:33 INFO - mozprofile == 0.28 03:09:33 INFO - mozrunner == 6.12 03:09:33 INFO - mozscreenshot == 0.1 03:09:33 INFO - mozsystemmonitor == 0.3 03:09:33 INFO - moztest == 0.7 03:09:33 INFO - mozversion == 1.4 03:09:33 INFO - psutil == 3.1.1 03:09:33 INFO - requests == 1.2.3 03:09:33 INFO - wsgiref == 0.1.2 03:09:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:33 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 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:33 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 03:09:33 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 03:09:33 INFO - Using env: {'DISPLAY': ':0', 03:09:33 INFO - 'HOME': '/home/cltbld', 03:09:33 INFO - 'LANG': 'en_US.UTF-8', 03:09:33 INFO - 'LANGUAGE': 'en_US:en', 03:09:33 INFO - 'LOGNAME': 'cltbld', 03:09:33 INFO - 'MAIL': '/var/mail/cltbld', 03:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:33 INFO - 'MOZ_NO_REMOTE': '1', 03:09:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:33 INFO - 'NO_EM_RESTART': '1', 03:09:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:33 INFO - 'PWD': '/builds/slave/test', 03:09:33 INFO - 'SHELL': '/bin/bash', 03:09:33 INFO - 'SHLVL': '1', 03:09:33 INFO - 'TERM': 'linux', 03:09:33 INFO - 'TMOUT': '86400', 03:09:33 INFO - 'USER': 'cltbld', 03:09:33 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:33 INFO - '_': '/tools/buildbot/bin/python'} 03:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 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)) 03:09:33 INFO - Cleaning up... 03:09:33 INFO - Return code: 0 03:09:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:33 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 0x161f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', '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 03:09:33 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 03:09:33 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 03:09:33 INFO - Using env: {'DISPLAY': ':0', 03:09:33 INFO - 'HOME': '/home/cltbld', 03:09:33 INFO - 'LANG': 'en_US.UTF-8', 03:09:33 INFO - 'LANGUAGE': 'en_US:en', 03:09:33 INFO - 'LOGNAME': 'cltbld', 03:09:33 INFO - 'MAIL': '/var/mail/cltbld', 03:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:33 INFO - 'MOZ_NO_REMOTE': '1', 03:09:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:33 INFO - 'NO_EM_RESTART': '1', 03:09:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:33 INFO - 'PWD': '/builds/slave/test', 03:09:33 INFO - 'SHELL': '/bin/bash', 03:09:33 INFO - 'SHLVL': '1', 03:09:33 INFO - 'TERM': 'linux', 03:09:33 INFO - 'TMOUT': '86400', 03:09:33 INFO - 'USER': 'cltbld', 03:09:33 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:33 INFO - '_': '/tools/buildbot/bin/python'} 03:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:09:33 INFO - Cleaning up... 03:09:33 INFO - Return code: 0 03:09:33 INFO - Running post-action listener: _resource_record_post_action 03:09:33 INFO - Running post-action listener: _start_resource_monitoring 03:09:33 INFO - Starting resource monitoring. 03:09:33 INFO - [mozharness: 2016-08-08 10:09:33.737917Z] Finished create-virtualenv step (success) 03:09:33 INFO - [mozharness: 2016-08-08 10:09:33.738396Z] Running install step. 03:09:33 INFO - Running pre-action listener: _resource_record_pre_action 03:09:33 INFO - Running main action method: install 03:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:33 INFO - Reading from file tmpfile_stdout 03:09:33 INFO - Detecting whether we're running mozinstall >=1.0... 03:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:09:34 INFO - Reading from file tmpfile_stdout 03:09:34 INFO - Output received: 03:09:34 INFO - Usage: mozinstall [options] installer 03:09:34 INFO - Options: 03:09:34 INFO - -h, --help show this help message and exit 03:09:34 INFO - -d DEST, --destination=DEST 03:09:34 INFO - Directory to install application into. [default: 03:09:34 INFO - "/builds/slave/test"] 03:09:34 INFO - --app=APP Application being installed. [default: firefox] 03:09:34 INFO - mkdir: /builds/slave/test/build/application 03:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:09:50 INFO - Reading from file tmpfile_stdout 03:09:50 INFO - Output received: 03:09:50 INFO - /builds/slave/test/build/application/firefox/firefox 03:09:50 INFO - Running post-action listener: _resource_record_post_action 03:09:50 INFO - [mozharness: 2016-08-08 10:09:50.746275Z] Finished install step (success) 03:09:50 INFO - [mozharness: 2016-08-08 10:09:50.746554Z] Running run-tests step. 03:09:50 INFO - Running pre-action listener: _resource_record_pre_action 03:09:50 INFO - Running main action method: run_tests 03:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:09:50 INFO - Python 2.7.3 03:09:50 INFO - Return code: 0 03:09:50 INFO - grabbing minidump binary from tooltool 03:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:50 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18de3b0>, '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 0x18df2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18df740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:09:50 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 03:09:50 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 03:09:50 INFO - Calling ['/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'] with output_timeout 600 03:09:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:09:50 INFO - Return code: 0 03:09:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:09:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:09:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:09:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:09:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:09:50 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/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:09:50 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/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 03:09:50 INFO - Using env: {'DISPLAY': ':0', 03:09:50 INFO - 'HOME': '/home/cltbld', 03:09:50 INFO - 'LANG': 'en_US.UTF-8', 03:09:50 INFO - 'LANGUAGE': 'en_US:en', 03:09:50 INFO - 'LOGNAME': 'cltbld', 03:09:50 INFO - 'MAIL': '/var/mail/cltbld', 03:09:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:09:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:50 INFO - 'MOZ_NO_REMOTE': '1', 03:09:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:50 INFO - 'NO_EM_RESTART': '1', 03:09:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:50 INFO - 'PWD': '/builds/slave/test', 03:09:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:09:50 INFO - 'SHELL': '/bin/bash', 03:09:50 INFO - 'SHLVL': '1', 03:09:50 INFO - 'TERM': 'linux', 03:09:50 INFO - 'TMOUT': '86400', 03:09:50 INFO - 'USER': 'cltbld', 03:09:50 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682', 03:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:50 INFO - '_': '/tools/buildbot/bin/python'} 03:09:50 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/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:09:51 INFO - mozversion application_buildid: 20160808000852 03:09:51 INFO - mozversion application_changeset: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 03:09:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:09:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:09:51 INFO - mozversion application_name: Firefox 03:09:51 INFO - mozversion application_remotingname: firefox-dev 03:09:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:09:51 INFO - mozversion application_vendor: Mozilla 03:09:51 INFO - mozversion application_version: 50.0a2 03:09:51 INFO - mozversion platform_buildid: 20160808000852 03:09:51 INFO - mozversion platform_changeset: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 03:09:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:09:51 INFO - mozversion platform_version: 50.0a2 03:09:51 INFO - using testdate: 1470650991 03:09:51 INFO - actual date: 1470650991 03:09:51 INFO - starting webserver on 'localhost:32952' 03:09:51 INFO - SUITE-START | Running 2 tests 03:09:51 INFO - TEST-START | dromaeo_css 03:09:51 INFO - operating with platform_type : linux_ 03:09:51 INFO - Initialising browser for dromaeo_css test... 03:09:51 INFO - TEST-INFO | started process 4648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP0l3et/profile http://localhost:32952/getInfo.html) 03:09:55 INFO - PROCESS | 4648 | __metrics Screen width/height:1600/1200 03:09:55 INFO - PROCESS | 4648 | colorDepth:24 03:09:55 INFO - PROCESS | 4648 | Browser inner width/height: 1024/697 03:09:55 INFO - PROCESS | 4648 | __metrics 03:09:55 INFO - PROCESS | 4648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:09:55 INFO - PROCESS | 4648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:09:56 INFO - TEST-INFO | 4648: exit 0 03:09:56 INFO - Browser initialized. 03:09:56 INFO - Running cycle 1/1 for dromaeo_css test... 03:09:56 INFO - TEST-INFO | started process 4737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP0l3et/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:08 INFO - PROCESS | 4737 | RSS: Main: 144912384 03:10:08 INFO - PROCESS | 4737 | 03:10:59 INFO - PROCESS | 4737 | 1470651059915 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] 03:13:08 INFO - PROCESS | 4737 | Cycle 1(1): loaded http://localhost:32952/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32952/tests/dromaeo/cssquery-ext.html) 03:13:10 INFO - PROCESS | 4737 | RSS: Main: 284045312 03:13:10 INFO - PROCESS | 4737 | 03:16:12 INFO - PROCESS | 4737 | Cycle 1(1): loaded http://localhost:32952/tests/dromaeo/cssquery-ext.html (next: http://localhost:32952/tests/dromaeo/cssquery-jquery.html) 03:16:13 INFO - PROCESS | 4737 | RSS: Main: 229871616 03:16:13 INFO - PROCESS | 4737 | 03:19:14 INFO - PROCESS | 4737 | Cycle 1(1): loaded http://localhost:32952/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32952/tests/dromaeo/cssquery-mootools.html) 03:19:15 INFO - PROCESS | 4737 | RSS: Main: 176152576 03:19:15 INFO - PROCESS | 4737 | 03:22:17 INFO - PROCESS | 4737 | Cycle 1(1): loaded http://localhost:32952/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32952/tests/dromaeo/cssquery-prototype.html) 03:22:18 INFO - PROCESS | 4737 | RSS: Main: 175943680 03:22:18 INFO - PROCESS | 4737 | 03:25:20 INFO - PROCESS | 4737 | Cycle 1(1): loaded http://localhost:32952/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32952/tests/dromaeo/cssquery-yui.html) 03:25:21 INFO - PROCESS | 4737 | RSS: Main: 180703232 03:25:21 INFO - PROCESS | 4737 | 03:28:23 INFO - PROCESS | 4737 | Cycle 1(1): loaded http://localhost:32952/tests/dromaeo/cssquery-yui.html (next: http://localhost:32952/tests/dromaeo/cssquery-dojo.html) 03:28:24 INFO - PROCESS | 4737 | RSS: Main: 262443008 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | __start_tp_report 03:28:24 INFO - PROCESS | 4737 | _x_x_mozilla_page_load 03:28:24 INFO - PROCESS | 4737 | _x_x_mozilla_page_load_details 03:28:24 INFO - PROCESS | 4737 | |i|pagename|runs| 03:28:24 INFO - PROCESS | 4737 | |0;dojo.html;3285.1294406982156;3299.884504042359;3301.5873015873017;3323.6676332366783;3339.5324654548367;5630.887382252357;5725.169850371693;5739.25389699339;5770.278715160605;5791.276090488684;5710.885782284352;5790.36306006339;5791.536677065835;5793.102069179274;5794.536437085034;5062.898742025157;5125.564327032207;5199.194124910636;5207.036698210824;5258.290130832327;5180.974095129519;5183.637145399824;5194.71429071401;5198.350206224222;5239.083160446926;5194.480551944813;5194.92207616886;5250.763715632786;5250.973745131269;5257.13257312543;5296.947030529704;5308.044551980642;5309.230161626542;5315.681059136464;5320.76056577455;5076.974615126937;5077.873053173664;5133.717645529495;5207.609429292818;5214.374275086976;5637.182608521783;5653.265075540154;5677.176809362661;5698.145278208261;5703.74333155009;6172.537059720494;6179.165812615284;6184.257889053342;6186.9381306186815;6189.226346706661;5992.820215393545;6103.84740381494;6104.175936248594;6105.8791806896725;6233.34549872266;5760.107183386582;5769.451902069339;5783.479486846204;5787.971060144757;5791.507721843606;6282.874342513124;6294.087357333161;6306.968465157736;6309.022101574264;6313.053042043638;5727.971360143256;5976.103584462278;6027.9095813562835;6038.245219347582;6100.561447286338;6186.721597528122;6188.257409110935;6194.318624951252;6195.039768835838;6195.31851496335;6083.543734219951;6136.631802091889;6142.802153580009;6154.692265386713;6161;5891.852703682443;5990.850228744229;6025.15647809307;6029.306629737549;6135.723892424851;4584.266517357207;4615.976920115378;4620.5411777634745;4621.145088158702;4684.953150468452;4126.793660316972;4138.461999939988;4144.70987030905;4162;4166.729162604421;5611.579131565149;5666.320041595035;5736.6844823534275;5746.9425305747245;5830.650160990354;6077.8480537986015;6083.300420451616;6087.756489740449;6094.39056094387;6097.237845269341;5730.74211660468;5733.254676892061;5735.082386818089;5737.967165910093;5740.196372507852;5016.272640467112;5023.623228257822;5044.873878153002;5048.1418158913075;5130.358705161855;6317.778877739257;6327.2090988625505;6327.462165715873;6331.2085989251345;6331.556791024676;6076.118962750288;6104.236970378703;6110.114033465212;6149.508039356751;6151.50787936955;6113.506711826627;6119;6161.44546990755;6166.352532983972;6262.185915830913;5971.970140149271;5972.343042265435;5973.790917317873;5975.163477113295;5999.040153575582;6051.667158306335;6056.152138700673;6061.605995610271;6062.272527296753;6064.969675151596;5873.442023007808;5971.970140149445;5998;6023.066424704178;6107.450329470369;5973.55198360116;6005.309389420273;6009.06859436788;6014.609050411885;6021.42796434338;5936.910946335895;5978.461938425563;5983.521318294439;5987.5808693391045;5996.2504686914135;5803.7678492861505;5826.271716035496;5967.671778052249;5992;6051.727672254847;5965.612235204698;5966.731497082555;5971.193888824953;5973.163757073926;5992.520598352208 03:28:24 INFO - PROCESS | 4737 | |1;ext.html;600.2916558461014;622.6855438003807;623.5853157650162;652.4030512081447;655.0534477679755;34584.82707586462;48879.51120488794;49451.258231126565;49729;49792.004159916825;50932.74533627336;51327.48672513274;51439.74280128603;51450;51616.74191629037;8065.879011814798;9190.540472976341;9441.480718560477;9441.669541566047;11039;10861.294015888981;11353.716157096056;11361.545538178505;11361.829572556418;11363.659090227306;11433;11470.885291147066;11472.139589530818;11472.942635286854;11482.196246262765;11326.15053870963;11341.206115571913;11342.262752921033;11377.317360958368;11408.942955285254;11185.217034807485;11240.831387529193;11243.212975091747;11260.831087533694;11261.43692815356;47133.76433117847;47557.28664070042;47632;47667;47678.76160619209;3781.470594116826;3824.063104539378;3894.941575876364;3905.243630106006;3944.1914407546383;36498.452523212174;36583.4512482313;36613.633863661285;36677.81661091677;36881.631183688085;4695.225287827504;4703.600193983515;4731.976340118277;4751.287306903958;4833.371661683994;386522;388520;389234;390018;392530;70268.64865675638;70331;70429.64785176145;70807;70846;40126.39810402846;41199.1760164801;41204.1759164815;43238;43377;19798.40604781859;19983.600327993357;19990.800091999186;20002.29991950304;20003.399898003085;13361.331933403291;14769.778453323208;15073.698526029419;15139.091654500766;15151.393944242327;21811.890940545196;21858.23496177658;21893.12427502914;22003.559928801333;22095.889520552293;7273.599952002691;7295.161056478467;7499.475036747375;7610.733624323122;7702.152763196044;1012.1548506996593;1085.310827624464;1103.3214573037524;1128.368113856243;1199.4527060092382;32692.673073269438;35080.47379289313;35615.10962225965;35957.46063809045;36259.637403625624;1180.7815554122344;1193.2065176657425;1196.3539688568076;1268.2137075013366;1297.1438850358718;18526.258949641862;18705.345312914254;18749;18750.906245468686;18762.34331798408;611385;618977;619048;619986;621521;68703.3129668697;69197.30802692009;69297.30702692909;69315.65342173257;69399.65300173467;1280.3406245783428;1416.2226405860579;1421.6161636358124;1462.1519518679354;1478.578605097542;35324.29351412906;37320.253594928494;37650.43524347192;37978;38107.23785524329;57504;58220.4177958215;58268;58303;58351;450050;453687;458266;460309;461143;31175.37649246957;32294.35411291808;32518.674813251564;34037.319253614296;34109.65890341164;54876.45123548713;55543.16685249643;55782;56078;56694;45020.54979450295;46586;47019.05961880675;47182.05635887332;48201;45120.514407812916;46134.76932615315;47238;47441.52558474371;48763;46759.53240467688;47225;48147.75926120487;48521.02957940751;48762.51237487722;39173;41633.58366416418;41739.582604174786;42091.50586633371;42290.154196916505;30610.387792244477;31221.8438907804;31445.908977866202;31630.209244769067;31690 03:28:24 INFO - PROCESS | 4737 | |2;jquery.html;391.9176972835704;405.393936065582;423.8516519218274;428.66135752755315;430.97629630370335;15098;20505.692414613757;20511.282105126324;20663.38009859703;21223.787762122374;20208.797912020877;20244.69632955503;20254;20298.492537686565;20545.075471603777;5745.080787074069;5860.736266867991;5893.941060589382;5961.344252132262;5985.042393217086;5782.537397008229;5825.650460972355;5825.8834823303505;5826.9708651456685;5827.184194212792;5613.578981576377;5805.042168042289;5834.5915785895;5919.112133180014;5928.081147422135;5880.529557635379;6042.123892035652;6393.456556192729;6461.998390249519;6476.287608363076;5874.618149820248;5879.588428809985;5931.08068249422;5936.5250779937505;5942.8811423771285;23991.7600823993;24009;24623.876880615662;24705.38236544083;24957.376065598324;2336.906523739048;2414.480886609387;2466.4203912080648;2615.5422801009745;2836.702146274653;10654.893451065467;10716.839247411295;10793.298435601739;10831.83752243717;10973.451327433597;1970.1823743146576;1976.8637404447509;2008.0963566395035;2051.148773259096;2090.174381119449;84951.57524212402;85259.72110418411;86754.13245867523;86809;86888;11336.94331528337;11503.53985840557;11713.1800773945;11823.113266505046;11860;16837.91581042087;16856.241469133674;16875.662486750196;16910.57723556921;16933.238004289837;8763.824723505622;8844.73465796027;8918.063603321687;8935.28517718581;9120.49837258957;8653.870191947126;8776.736697899074;8824.955875220583;8884.73345799614;9011.143941325563;5009.874753131201;5056.57019153376;5083.542481176638;5098.164660722137;5101.285819985205;2032.8576999609886;2092.780258072911;2146.527763891941;2153.4078128514784;2174.5433458973794;976.5898322704339;1045.5033858917106;1053.6154303679255;1055.6147006342783;1078.5739632844982;20528.281510147073;21090.156393744386;21133.78866211349;21190.682139767916;21275.255366062112;1055.7360659835042;1066.919981001428;1067.2102644043352;1081.0648788797632;1117.7485065860117;11580.131490138274;11766.764664706658;11914.46384912681;11947.880521194851;11951.641450756491;477134;484293;486981;506086;540503;94483;95638.52180739191;96831;98520.50739746398;106592;1048.533402635835;1055.4669891704793;1084.2464487181333;1090.0135377483232;1092.2026920348032;16074.839251607335;18164.636707266043;18172.90913545424;18251.178696958403;18716.625667486845;45636;45859.77070114628;46012.30981535347;46406.53593464022;47664.2850357238;62646.37353626588;63607.68196158989;64587.354126458136;65100.674496627216;66185.66907165434;8754.124587541195;8774.736757897273;8955.41789783662;9023.954880225558;9115.635374585208;10689.465526723789;13420.731585368434;13819.930900345435;14136.43454261818;14179.929100354433;10896.673099807018;11232.55069797199;11447.084233261152;11534.250273732181;12110.849469300403;11581;11849.940750296193;12068.456919438822;12156.756864862477;12228.266304021787;11094.833577496505;11348.602798901999;11443.942780286045;11747.236429632047;12112;8383.832323353621;8400.495970241805;8510.74467765968;8590.742277731679;8797.692080777142;6683;10820.134389248684;10910.399928004035;11461.885381146081;11571.19001669875 03:28:24 INFO - PROCESS | 4737 | |3;mootools.html;1423.6156237815783;1558.3065535836554;1597.08167803513;1779.8932064076157;1913.0912816412197;8110.756677299683;9380.85928711069;9657.661981830637;9663.323567350279;10044;9695.951520242406;9803.509824508781;9804.019598040193;9805.607775688964;9814.950925245364;2572.5626643470655;2598.142612937726;2600.4149066460086;2603.2060221632396;2603.7916966642624;2490.302715239736;2587.6118582212725;2592.8703564821685;2595.4809038192343;2595.5717306644474;2522.0794410040385;2564.9358766030814;2571.90998315059;2579.7291284415055;2581.7805486533666;2509.234683421565;2519.2694118705554;2524.0787112703915;2527.835690680109;2537.314924970266;2448.4735781807;2453.877306134686;2458.483718419134;2465.0386349323776;2511.510255500178;9625.326227164102;9759.316847820655;9808.411495310233;9818.361806482555;9822.410655360629;5686.09022556389;5971.0744834550715;5992.370801065869;6013.51891848651;6033.78881739137;3669.688076513473;3758.699304055669;3784.489093972306;3806.942895856537;3821.2739579479808;1191.4987116233522;1211.9879900283274;1228.2753175626333;1230.5262473947505;1278.0861683895967;13911.652208694763;13965.092269002482;13974.650633734238;14022.228777417238;14056.226907520184;4856.368672072678;4986.401631804134;5047.343845300087;5068.265101560253;5101.183810590288;10768.46157692128;10769.623063192907;10789.406582637874;10801.081908037748;10807.945960270148;5724.656520608777;5725.541956643458;5726.055200891889;5728.598998070179;5729.312482502043;4704.08270387278;4751.501092385319;5302.522772950376;5303.893922121536;5331.81338653153;4278.336857787048;4296.892577685583;4359.2807186813825;4386.583274588909;4397.1865204937185;1257.69186549296;1279.8821632596205;1288.4137717338588;1295.054610134588;1295.8639342868862;1061.830107182847;1076.3488089705772;1087.543231842628;1088.2871719024051;1094.5402930769096;6391.616503009835;6550.3449655035065;6624.205095388585;6796.626185559743;6807.965960170168;479.966402351832;483.8596806925952;488.9291052797324;498.11833055491576;513.4454788828132;4535.818567257273;4542.750148741851;4603.401557797465;4647.140279048387;4665.6734028617675;12202.633920982385;12580.622581322576;12680.365981700877;12693.238405695687;12697.492100315883;11430.657080287605;11990.820137697941;12012.759744805055;12564.246145231133;12600.81098783519;8855.51294678786;9039.457632541937;9048.185663290336;9052.683156089506;9056.728298151065;6612.8346791330605;6889.483288753464;6920.065791118134;6960.582365058112;6972.546784458994;11130.888691112985;11133.94433027862;11413.144014199135;11489.82765258505;11532.82700759506;9440.527973601429;11400.543978240778;11507.539698412305;11774.882251177378;11847.585334513251;8799.252063574411;9024.142706442877;9094.090590941118;9110.772230694285;9128.726138215865;9500.287478439282;9665;9668.516574171123;9675.70972870815;9677.516124193622;8488.787780305542;8595.269402100892;8610.698625548075;8755.781105472159;8853;8623.482591044558;8675.175858293453;8676.305895528469;8698.21716045559;8855.867161992446;7533.698652053857;7603.201663825219;7669.7315593953945;7681;7686.692532298645;5491.395946445808;5554.416786237549;5575.832725018256;5627;5872.618279811799;5506.614536982373;5602.047651899105;5646.858828529155;5741.110127930177;5827.213326201078 03:28:24 INFO - PROCESS | 4737 | |4;prototype.html;328.69431428771236;333.8013881740364;334.8275638046408;343.157548219122;350.23298975244205;5651.265335506384;5670.036093864042;5675.886482270357;5683.943160568393;5701.458361455665;5575.553955683546;5600.915986260209;5608.719564021803;5624.831255062355;5629.577781666381;4207;4219.156168766243;4378.299472084467;4402.273624851896;4403.713758605696;4366.148601022786;4375.846845360413;4378.737275763448;4403.669724770639;4431.8670439886855;4296.334068219431;4348.369486424483;4352.216601011816;4360.23695853226;4397.934030989538;4067.3085575452096;4275.850345237917;4332.870013899556;4381.715188512737;4418.536053714346;3918.314294998364;3922.4704664870164;3930.508686414198;3935.6457918787446;3942.172143849795;5449.4005659377835;5470.671759694431;5505.834824955257;5526.309211348582;5528.5024347808485;4758.738269395182;4932.7040377577105;4965.702057876539;4971.254311853251;4990.800367985276;5813.912791308091;5817;5819.592628516006;5838.970805145947;5844.970775146097;4144.647704945083;4237;4240.512341080785;4254.7234429762275;4271.893202669927;6009.909851352233;6058.39416058395;6068.484178844804;6077.301110372319;6078.848028799315;4289.935650965238;4290.2706539888295;4293.97853010733;4302.9569704303185;4322.22200003996;4190.643795277375;4326.15639950213;4326.891827704269;4352.260115780262;4362.236608593484;4873.610111191097;4928.531789479994;4944.3572335596145;4964.503549645079;4989.151844186497;5863.149843272701;5900.734466948998;5909.172715819789;5961.4038596140035;5962.731677074456;1498.423107103773;1504.2253239581623;1521.7413039783264;1527.1676936069646;1529.915854627984;954.0650162840456;960.9135177833889;974.6783561424713;982.4547376206132;987.9851802222972;5564.5254007688;5692.1461780733225;5724.627899186539;5730.398308177581;5732.742026608729;6012.368701286389;6049.304330002106;6049.879002419927;6061.363556826558;6073.51411887048;5598.992181407304;5610.691411972381;5643.661380317112;5699.2590963174525;5735.279416175131;3169.683031696858;3202.727768139734;3213.826953162079;3217.613886333655;3222.8227447490613;6005.549583781234;6150.261968563801;6220.844478887973;6258.968705156445;6261.812145635638;6069.848253793691;6079.635221886612;6090.695465226721;6104.8779024420155;6109.7861574844665;6179.443850053517;6188.9885787119365;6191.318954914956;6236.501079913505;6239.500839932885;5844.129878438859;5938.228030356199;5968.46283834457;5975.760969561169;6016.789412370546;5901.439363260483;5910.231669882888;5932.911006334822;5976.462118409364;5986.16193732869;5942.762289508371;6118.684482836212;6152.292486364126;6210.472109870711;6214.9067763984485;4204.936925946175;4206.726562773532;4208.22147902639;4218.282891908321;4231.365295205744;5955.6724380157775;6050.334463208956;6109.236345456818;6130.295016073031;6136.7852125175405;5770.432157689787;5901;5909.438603332676;5910.497607703222;5946.375630558902;5859.531237500904;5867.794627188028;5880.441358070976;5882.205902203148;5889.793857214977;5540.501354878081;5761.971190144022;5766.2989418120915;5840.853978650398;5872.588918775816;5858.238429004202;5888.205092312654;5894.587378883437;5913.255589601164;5916.674582897982;5766.94233057664;5777.45114214149;5780.450857168562;5784.7107644618445;5791.971040144772 03:28:24 INFO - PROCESS | 4737 | |5;yui.html;810.6302427436714;832.3507664022057;846.2722059029234;849.3884403229674;857.5712143928037;16500.66998660026;17626.383076592323;17674.823251767477;17689.734653980184;17690.38083667072;14725.410983560645;14903.850961490381;14919.030263032922;14935.626609334771;14944.327505262263;692.9930700693004;843.7679638099511;865.9350548708841;870.0951010948606;873.6898401067625;879.9560021998874;881.1452890696015;881.8280435315116;882.3206131278912;882.7837179890936;952.6475204174466;953.5947222430474;954.3939598355025;955.0926619711267;955.2692190474293;727.7089164334283;750.2497502497503;753.8567672142275;883.1830556735059;947.4741518457254;681.7136802542943;683.7094234950176;683.8016975077221;683.8222062263798;684.1755684400335;15891.36434542617;15953.760693589606;15991.440299589458;16006.759898601531;16046.438374656947;14936;15185.392584296615;15307.387704491919;15337;15643.296051677702;14045.64885877851;14101.929490352482;14239.216843073627;14462;14510.27448627575;4619.422572178478;4670.556297151799;4716;4768.785404656799;4786.497417771118;83129.58435207845;84805;92394.5380273101;92751.53624231835;92957.5352123235;11349.886501135048;11750.23623464472;11773.529058837721;11914.702132446759;12113.15207935436;24311;24322.756772432404;24335.14826981047;24351.51296974086;24357.26928192157;8622.741317760478;8861.601227944759;9004.729858104267;9062.486064383822;9293.256539476826;4835.2505361669;4947.802087916443;5107.821226257138;5160.612954028463;5225.069546065243;3831.099691572478;3985.940210896839;3990.700697447703;3996.140829721624;3998.520177578709;662.2615783401473;667.9131712877295;671.6775947545206;687.3847906123991;689.069755829626;720.6396801599201;758.0978635423828;761.7219714804056;767.7888580640257;774.6591499740097;9465.337426380087;10070.546825392876;10082.546285417175;10241.897581024243;10267.229957753198;581.7905554000556;659.3768888400512;709.0392518137952;729.941604671625;793.5278509287081;4720.905581888343;4865.9026819463415;4931.624101757714;4964.925526117111;5065.240213967861;71675.64162179155;98530;98714;128750;135832;97610;112159;114987;118738.40630796964;120366;67841.32158678448;77936;83162;105587.47206264074;110240;10278.537465813906;10758.73103172427;10809.783804324026;11275.943620281847;11282.435878205893;58470;59978.40021599903;60379.09431358621;61263.38736612577;62461.68769156307;68944.6552767233;77321.2267877314;78155.60922195352;83287.5835620818;83794;14933.850661493247;15078.623034424227;15423.69152616919;15935;16206.91896540557;15582.376704932129;15684.372625094868;16443.753343699616;16551.172441377643;17069.06120163403;14500.419983200554;15458.304376303318;15466.690666186389;15966.20168991569;16180.838191617933;13644.65888352799;13663;13713.17720936747;14509.347079381241;15017.174055427056;15118.924405377902;15184.46854360092;15414.922925385301;15446.382144714085;16239.350425982828;11646.534138634359;12221.450034748636;12270.4478298475;12323.568675096329;12976.545820896223;12275.559543392439;12335.69160770952;12523;12685.746285074063;12876.549320773727 03:28:24 INFO - PROCESS | 4737 | __end_tp_report 03:28:24 INFO - PROCESS | 4737 | __start_cc_report 03:28:24 INFO - PROCESS | 4737 | _x_x_mozilla_cycle_collect,1259 03:28:24 INFO - PROCESS | 4737 | __end_cc_report 03:28:24 INFO - PROCESS | 4737 | __startTimestamp1470652104144__endTimestamp 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | ------- Summary: start ------- 03:28:24 INFO - PROCESS | 4737 | Number of tests: 6 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | [#0] dojo.html Cycles:165 Average:5670.40 Median:5966.73 stddev:650.08 (10.9%) stddev-sans-first:624.56 03:28:24 INFO - PROCESS | 4737 | Values: 3285.1 3299.9 3301.6 3323.7 3339.5 5630.9 5725.2 5739.3 5770.3 5791.3 5710.9 5790.4 5791.5 5793.1 5794.5 5062.9 5125.6 5199.2 5207.0 5258.3 5181.0 5183.6 5194.7 5198.4 5239.1 5194.5 5194.9 5250.8 5251.0 5257.1 5296.9 5308.0 5309.2 5315.7 5320.8 5077.0 5077.9 5133.7 5207.6 5214.4 5637.2 5653.3 5677.2 5698.1 5703.7 6172.5 6179.2 6184.3 6186.9 6189.2 5992.8 6103.8 6104.2 6105.9 6233.3 5760.1 5769.5 5783.5 5788.0 5791.5 6282.9 6294.1 6307.0 6309.0 6313.1 5728.0 5976.1 6027.9 6038.2 6100.6 6186.7 6188.3 6194.3 6195.0 6195.3 6083.5 6136.6 6142.8 6154.7 6161.0 5891.9 5990.9 6025.2 6029.3 6135.7 4584.3 4616.0 4620.5 4621.1 4685.0 4126.8 4138.5 4144.7 4162.0 4166.7 5611.6 5666.3 5736.7 5746.9 5830.7 6077.8 6083.3 6087.8 6094.4 6097.2 5730.7 5733.3 5735.1 5738.0 5740.2 5016.3 5023.6 5044.9 5048.1 5130.4 6317.8 6327.2 6327.5 6331.2 6331.6 6076.1 6104.2 6110.1 6149.5 6151.5 6113.5 6119.0 6161.4 6166.4 6262.2 5972.0 5972.3 5973.8 5975.2 5999.0 6051.7 6056.2 6061.6 6062.3 6065.0 5873.4 5972.0 5998.0 6023.1 6107.5 5973.6 6005.3 6009.1 6014.6 6021.4 5936.9 5978.5 5983.5 5987.6 5996.3 5803.8 5826.3 5967.7 5992.0 6051.7 5965.6 5966.7 5971.2 5973.2 5992.5 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | [#1] ext.html Cycles:180 Average:66994.48 Median:215851.50 stddev:131860.13 (61.1%) stddev-sans-first:132135.81 03:28:24 INFO - PROCESS | 4737 | Values: 600.3 622.7 623.6 652.4 655.1 34584.8 48879.5 49451.3 49729.0 49792.0 50932.7 51327.5 51439.7 51450.0 51616.7 8065.9 9190.5 9441.5 9441.7 11039.0 10861.3 11353.7 11361.5 11361.8 11363.7 11433.0 11470.9 11472.1 11472.9 11482.2 11326.2 11341.2 11342.3 11377.3 11408.9 11185.2 11240.8 11243.2 11260.8 11261.4 47133.8 47557.3 47632.0 47667.0 47678.8 3781.5 3824.1 3894.9 3905.2 3944.2 36498.5 36583.5 36613.6 36677.8 36881.6 4695.2 4703.6 4732.0 4751.3 4833.4 386522.0 388520.0 389234.0 390018.0 392530.0 70268.6 70331.0 70429.6 70807.0 70846.0 40126.4 41199.2 41204.2 43238.0 43377.0 19798.4 19983.6 19990.8 20002.3 20003.4 13361.3 14769.8 15073.7 15139.1 15151.4 21811.9 21858.2 21893.1 22003.6 22095.9 7273.6 7295.2 7499.5 7610.7 7702.2 1012.2 1085.3 1103.3 1128.4 1199.5 32692.7 35080.5 35615.1 35957.5 36259.6 1180.8 1193.2 1196.4 1268.2 1297.1 18526.3 18705.3 18749.0 18750.9 18762.3 611385.0 618977.0 619048.0 619986.0 621521.0 68703.3 69197.3 69297.3 69315.7 69399.7 1280.3 1416.2 1421.6 1462.2 1478.6 35324.3 37320.3 37650.4 37978.0 38107.2 57504.0 58220.4 58268.0 58303.0 58351.0 450050.0 453687.0 458266.0 460309.0 461143.0 31175.4 32294.4 32518.7 34037.3 34109.7 54876.5 55543.2 55782.0 56078.0 56694.0 45020.5 46586.0 47019.1 47182.1 48201.0 45120.5 46134.8 47238.0 47441.5 48763.0 46759.5 47225.0 48147.8 48521.0 48762.5 39173.0 41633.6 41739.6 42091.5 42290.2 30610.4 31221.8 31445.9 31630.2 31690.0 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | [#2] jquery.html Cycles:180 Average:30208.10 Median:24000.38 stddev:82583.77 (344.1%) stddev-sans-first:82785.10 03:28:24 INFO - PROCESS | 4737 | Values: 391.9 405.4 423.9 428.7 431.0 15098.0 20505.7 20511.3 20663.4 21223.8 20208.8 20244.7 20254.0 20298.5 20545.1 5745.1 5860.7 5893.9 5961.3 5985.0 5782.5 5825.7 5825.9 5827.0 5827.2 5613.6 5805.0 5834.6 5919.1 5928.1 5880.5 6042.1 6393.5 6462.0 6476.3 5874.6 5879.6 5931.1 5936.5 5942.9 23991.8 24009.0 24623.9 24705.4 24957.4 2336.9 2414.5 2466.4 2615.5 2836.7 10654.9 10716.8 10793.3 10831.8 10973.5 1970.2 1976.9 2008.1 2051.1 2090.2 84951.6 85259.7 86754.1 86809.0 86888.0 11336.9 11503.5 11713.2 11823.1 11860.0 16837.9 16856.2 16875.7 16910.6 16933.2 8763.8 8844.7 8918.1 8935.3 9120.5 8653.9 8776.7 8825.0 8884.7 9011.1 5009.9 5056.6 5083.5 5098.2 5101.3 2032.9 2092.8 2146.5 2153.4 2174.5 976.6 1045.5 1053.6 1055.6 1078.6 20528.3 21090.2 21133.8 21190.7 21275.3 1055.7 1066.9 1067.2 1081.1 1117.7 11580.1 11766.8 11914.5 11947.9 11951.6 477134.0 484293.0 486981.0 506086.0 540503.0 94483.0 95638.5 96831.0 98520.5 106592.0 1048.5 1055.5 1084.2 1090.0 1092.2 16074.8 18164.6 18172.9 18251.2 18716.6 45636.0 45859.8 46012.3 46406.5 47664.3 62646.4 63607.7 64587.4 65100.7 66185.7 8754.1 8774.7 8955.4 9024.0 9115.6 10689.5 13420.7 13819.9 14136.4 14179.9 10896.7 11232.6 11447.1 11534.3 12110.8 11581.0 11849.9 12068.5 12156.8 12228.3 11094.8 11348.6 11443.9 11747.2 12112.0 8383.8 8400.5 8510.7 8590.7 8797.7 6683.0 10820.1 10910.4 11461.9 11571.2 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | [#3] mootools.html Cycles:180 Average:6425.76 Median:4727.79 stddev:3728.33 (78.9%) stddev-sans-first:3719.83 03:28:24 INFO - PROCESS | 4737 | Values: 1423.6 1558.3 1597.1 1779.9 1913.1 8110.8 9380.9 9657.7 9663.3 10044.0 9696.0 9803.5 9804.0 9805.6 9815.0 2572.6 2598.1 2600.4 2603.2 2603.8 2490.3 2587.6 2592.9 2595.5 2595.6 2522.1 2564.9 2571.9 2579.7 2581.8 2509.2 2519.3 2524.1 2527.8 2537.3 2448.5 2453.9 2458.5 2465.0 2511.5 9625.3 9759.3 9808.4 9818.4 9822.4 5686.1 5971.1 5992.4 6013.5 6033.8 3669.7 3758.7 3784.5 3806.9 3821.3 1191.5 1212.0 1228.3 1230.5 1278.1 13911.7 13965.1 13974.7 14022.2 14056.2 4856.4 4986.4 5047.3 5068.3 5101.2 10768.5 10769.6 10789.4 10801.1 10807.9 5724.7 5725.5 5726.1 5728.6 5729.3 4704.1 4751.5 5302.5 5303.9 5331.8 4278.3 4296.9 4359.3 4386.6 4397.2 1257.7 1279.9 1288.4 1295.1 1295.9 1061.8 1076.3 1087.5 1088.3 1094.5 6391.6 6550.3 6624.2 6796.6 6808.0 480.0 483.9 488.9 498.1 513.4 4535.8 4542.8 4603.4 4647.1 4665.7 12202.6 12580.6 12680.4 12693.2 12697.5 11430.7 11990.8 12012.8 12564.2 12600.8 8855.5 9039.5 9048.2 9052.7 9056.7 6612.8 6889.5 6920.1 6960.6 6972.5 11130.9 11133.9 11413.1 11489.8 11532.8 9440.5 11400.5 11507.5 11774.9 11847.6 8799.3 9024.1 9094.1 9110.8 9128.7 9500.3 9665.0 9668.5 9675.7 9677.5 8488.8 8595.3 8610.7 8755.8 8853.0 8623.5 8675.2 8676.3 8698.2 8855.9 7533.7 7603.2 7669.7 7681.0 7686.7 5491.4 5554.4 5575.8 5627.0 5872.6 5506.6 5602.0 5646.9 5741.1 5827.2 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | [#4] prototype.html Cycles:180 Average:4944.11 Median:5700.36 stddev:1456.07 (25.5%) stddev-sans-first:1418.35 03:28:24 INFO - PROCESS | 4737 | Values: 328.7 333.8 334.8 343.2 350.2 5651.3 5670.0 5675.9 5683.9 5701.5 5575.6 5600.9 5608.7 5624.8 5629.6 4207.0 4219.2 4378.3 4402.3 4403.7 4366.1 4375.8 4378.7 4403.7 4431.9 4296.3 4348.4 4352.2 4360.2 4397.9 4067.3 4275.9 4332.9 4381.7 4418.5 3918.3 3922.5 3930.5 3935.6 3942.2 5449.4 5470.7 5505.8 5526.3 5528.5 4758.7 4932.7 4965.7 4971.3 4990.8 5813.9 5817.0 5819.6 5839.0 5845.0 4144.6 4237.0 4240.5 4254.7 4271.9 6009.9 6058.4 6068.5 6077.3 6078.8 4289.9 4290.3 4294.0 4303.0 4322.2 4190.6 4326.2 4326.9 4352.3 4362.2 4873.6 4928.5 4944.4 4964.5 4989.2 5863.1 5900.7 5909.2 5961.4 5962.7 1498.4 1504.2 1521.7 1527.2 1529.9 954.1 960.9 974.7 982.5 988.0 5564.5 5692.1 5724.6 5730.4 5732.7 6012.4 6049.3 6049.9 6061.4 6073.5 5599.0 5610.7 5643.7 5699.3 5735.3 3169.7 3202.7 3213.8 3217.6 3222.8 6005.5 6150.3 6220.8 6259.0 6261.8 6069.8 6079.6 6090.7 6104.9 6109.8 6179.4 6189.0 6191.3 6236.5 6239.5 5844.1 5938.2 5968.5 5975.8 6016.8 5901.4 5910.2 5932.9 5976.5 5986.2 5942.8 6118.7 6152.3 6210.5 6214.9 4204.9 4206.7 4208.2 4218.3 4231.4 5955.7 6050.3 6109.2 6130.3 6136.8 5770.4 5901.0 5909.4 5910.5 5946.4 5859.5 5867.8 5880.4 5882.2 5889.8 5540.5 5762.0 5766.3 5840.9 5872.6 5858.2 5888.2 5894.6 5913.3 5916.7 5766.9 5777.5 5780.5 5784.7 5792.0 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - PROCESS | 4737 | [#5] yui.html Cycles:180 Average:22438.00 Median:3997.33 stddev:31885.50 (797.7%) stddev-sans-first:31933.59 03:28:24 INFO - PROCESS | 4737 | Values: 810.6 832.4 846.3 849.4 857.6 16500.7 17626.4 17674.8 17689.7 17690.4 14725.4 14903.9 14919.0 14935.6 14944.3 693.0 843.8 865.9 870.1 873.7 880.0 881.1 881.8 882.3 882.8 952.6 953.6 954.4 955.1 955.3 727.7 750.2 753.9 883.2 947.5 681.7 683.7 683.8 683.8 684.2 15891.4 15953.8 15991.4 16006.8 16046.4 14936.0 15185.4 15307.4 15337.0 15643.3 14045.6 14101.9 14239.2 14462.0 14510.3 4619.4 4670.6 4716.0 4768.8 4786.5 83129.6 84805.0 92394.5 92751.5 92957.5 11349.9 11750.2 11773.5 11914.7 12113.2 24311.0 24322.8 24335.1 24351.5 24357.3 8622.7 8861.6 9004.7 9062.5 9293.3 4835.3 4947.8 5107.8 5160.6 5225.1 3831.1 3985.9 3990.7 3996.1 3998.5 662.3 667.9 671.7 687.4 689.1 720.6 758.1 761.7 767.8 774.7 9465.3 10070.5 10082.5 10241.9 10267.2 581.8 659.4 709.0 729.9 793.5 4720.9 4865.9 4931.6 4964.9 5065.2 71675.6 98530.0 98714.0 128750.0 135832.0 97610.0 112159.0 114987.0 118738.4 120366.0 67841.3 77936.0 83162.0 105587.5 110240.0 10278.5 10758.7 10809.8 11275.9 11282.4 58470.0 59978.4 60379.1 61263.4 62461.7 68944.7 77321.2 78155.6 83287.6 83794.0 14933.9 15078.6 15423.7 15935.0 16206.9 15582.4 15684.4 16443.8 16551.2 17069.1 14500.4 15458.3 15466.7 15966.2 16180.8 13644.7 13663.0 13713.2 14509.3 15017.2 15118.9 15184.5 15414.9 15446.4 16239.4 11646.5 12221.5 12270.4 12323.6 12976.5 12275.6 12335.7 12523.0 12685.7 12876.5 03:28:24 INFO - PROCESS | 4737 | -------- Summary: end -------- 03:28:24 INFO - PROCESS | 4737 | 03:28:24 INFO - TEST-INFO | 4737: exit 0 03:28:24 INFO - TEST-OK | dromaeo_css | took 1113894ms 03:28:24 INFO - TEST-START | kraken 03:28:24 INFO - operating with platform_type : linux_ 03:28:24 INFO - Initialising browser for kraken test... 03:28:24 INFO - TEST-INFO | started process 4943 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBXZDFo/profile http://localhost:32952/getInfo.html) 03:28:29 INFO - PROCESS | 4943 | __metrics Screen width/height:1600/1200 03:28:29 INFO - PROCESS | 4943 | colorDepth:24 03:28:29 INFO - PROCESS | 4943 | Browser inner width/height: 1024/697 03:28:29 INFO - PROCESS | 4943 | __metrics 03:28:30 INFO - PROCESS | 4943 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:28:30 INFO - PROCESS | 4943 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:28:31 INFO - TEST-INFO | 4943: exit 0 03:28:31 INFO - Browser initialized. 03:28:31 INFO - Running cycle 1/1 for kraken test... 03:28:31 INFO - TEST-INFO | started process 5032 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBXZDFo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:28:42 INFO - PROCESS | 5032 | RSS: Main: 145195008 03:28:42 INFO - PROCESS | 5032 | 03:28:45 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32952/tests/kraken/driver.html?testName=audio-beat-detection) 03:28:46 INFO - PROCESS | 5032 | RSS: Main: 191418368 03:28:46 INFO - PROCESS | 5032 | 03:28:48 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32952/tests/kraken/driver.html?testName=audio-dft) 03:28:49 INFO - PROCESS | 5032 | RSS: Main: 181665792 03:28:49 INFO - PROCESS | 5032 | 03:28:51 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32952/tests/kraken/driver.html?testName=audio-fft) 03:28:52 INFO - PROCESS | 5032 | RSS: Main: 268525568 03:28:52 INFO - PROCESS | 5032 | 03:28:54 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32952/tests/kraken/driver.html?testName=audio-oscillator) 03:28:55 INFO - PROCESS | 5032 | RSS: Main: 175509504 03:28:55 INFO - PROCESS | 5032 | 03:28:57 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32952/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:28:57 INFO - PROCESS | 5032 | RSS: Main: 177098752 03:28:57 INFO - PROCESS | 5032 | 03:29:00 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32952/tests/kraken/driver.html?testName=imaging-darkroom) 03:29:01 INFO - PROCESS | 5032 | RSS: Main: 213057536 03:29:01 INFO - PROCESS | 5032 | 03:29:04 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32952/tests/kraken/driver.html?testName=imaging-desaturate) 03:29:05 INFO - PROCESS | 5032 | RSS: Main: 212402176 03:29:05 INFO - PROCESS | 5032 | 03:29:08 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32952/tests/kraken/driver.html?testName=json-parse-financial) 03:29:08 INFO - PROCESS | 5032 | RSS: Main: 212656128 03:29:08 INFO - PROCESS | 5032 | 03:29:10 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32952/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:29:10 INFO - PROCESS | 5032 | RSS: Main: 176324608 03:29:10 INFO - PROCESS | 5032 | 03:29:12 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:29:13 INFO - PROCESS | 5032 | RSS: Main: 182304768 03:29:13 INFO - PROCESS | 5032 | 03:29:15 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:29:15 INFO - PROCESS | 5032 | RSS: Main: 186720256 03:29:15 INFO - PROCESS | 5032 | 03:29:18 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:29:19 INFO - PROCESS | 5032 | RSS: Main: 182300672 03:29:19 INFO - PROCESS | 5032 | 03:29:21 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:29:22 INFO - PROCESS | 5032 | RSS: Main: 185057280 03:29:22 INFO - PROCESS | 5032 | 03:29:23 INFO - PROCESS | 5032 | Cycle 1(1): loaded http://localhost:32952/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32952/tests/kraken/driver.html?testName=ai-astar) 03:29:24 INFO - PROCESS | 5032 | RSS: Main: 235761664 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | __start_tp_report 03:29:24 INFO - PROCESS | 5032 | _x_x_mozilla_page_load 03:29:24 INFO - PROCESS | 5032 | _x_x_mozilla_page_load_details 03:29:24 INFO - PROCESS | 5032 | |i|pagename|runs| 03:29:24 INFO - PROCESS | 5032 | |0;ai-astar;102;113;109;111;112;109;111;110;110;110 03:29:24 INFO - PROCESS | 5032 | |1;audio-beat-detection;144;115;114;164;116;117;137;115;154;115 03:29:24 INFO - PROCESS | 5032 | |2;audio-dft;160;161;160;161;161;161;161;160;160;160 03:29:24 INFO - PROCESS | 5032 | |3;audio-fft;75;74;74;123;75;76;74;75;113;75 03:29:24 INFO - PROCESS | 5032 | |4;audio-oscillator;82;84;121;85;84;83;121;82;83;122 03:29:24 INFO - PROCESS | 5032 | |5;imaging-gaussian-blur;116;117;117;116;117;117;116;116;117;117 03:29:24 INFO - PROCESS | 5032 | |6;imaging-darkroom;145;146;145;145;145;146;146;146;146;145 03:29:24 INFO - PROCESS | 5032 | |7;imaging-desaturate;95;94;95;95;95;95;94;95;95;94 03:29:24 INFO - PROCESS | 5032 | |8;json-parse-financial;53;52;51;52;51;53;53;52;50;51 03:29:24 INFO - PROCESS | 5032 | |9;json-stringify-tinderbox;61;63;63;63;62;61;61;62;63;63 03:29:24 INFO - PROCESS | 5032 | |10;stanford-crypto-aes;77;72;81;71;74;72;72;72;74;76 03:29:24 INFO - PROCESS | 5032 | |11;stanford-crypto-ccm;117;116;126;125;116;124;117;116;159;122 03:29:24 INFO - PROCESS | 5032 | |12;stanford-crypto-pbkdf2;151;150;149;149;153;153;148;152;150;150 03:29:24 INFO - PROCESS | 5032 | |13;stanford-crypto-sha256-iterative;63;62;62;61;65;61;63;61;59;61 03:29:24 INFO - PROCESS | 5032 | __end_tp_report 03:29:24 INFO - PROCESS | 5032 | __start_cc_report 03:29:24 INFO - PROCESS | 5032 | _x_x_mozilla_cycle_collect,1607 03:29:24 INFO - PROCESS | 5032 | __end_cc_report 03:29:24 INFO - PROCESS | 5032 | __startTimestamp1470652164082__endTimestamp 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | ------- Summary: start ------- 03:29:24 INFO - PROCESS | 5032 | Number of tests: 14 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:2.98 (2.7%) stddev-sans-first:1.33 03:29:24 INFO - PROCESS | 5032 | Values: 102.0 113.0 109.0 111.0 112.0 109.0 111.0 110.0 110.0 110.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:127.00 stddev:19.05 (15.0%) stddev-sans-first:19.42 03:29:24 INFO - PROCESS | 5032 | Values: 144.0 115.0 114.0 164.0 116.0 117.0 137.0 115.0 154.0 115.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 03:29:24 INFO - PROCESS | 5032 | Values: 160.0 161.0 160.0 161.0 161.0 161.0 161.0 160.0 160.0 160.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#3] audio-fft Cycles:10 Average:83.40 Median:75.00 stddev:18.40 (24.5%) stddev-sans-first:19.26 03:29:24 INFO - PROCESS | 5032 | Values: 75.0 74.0 74.0 123.0 75.0 76.0 74.0 75.0 113.0 75.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.00 stddev:18.40 (22.2%) stddev-sans-first:18.94 03:29:24 INFO - PROCESS | 5032 | Values: 82.0 84.0 121.0 85.0 84.0 83.0 121.0 82.0 83.0 122.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 03:29:24 INFO - PROCESS | 5032 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 03:29:24 INFO - PROCESS | 5032 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 146.0 146.0 146.0 145.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 03:29:24 INFO - PROCESS | 5032 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.00 03:29:24 INFO - PROCESS | 5032 | Values: 53.0 52.0 51.0 52.0 51.0 53.0 53.0 52.0 50.0 51.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:63.00 stddev:0.92 (1.5%) stddev-sans-first:0.87 03:29:24 INFO - PROCESS | 5032 | Values: 61.0 63.0 63.0 63.0 62.0 61.0 61.0 62.0 63.0 63.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:74.00 stddev:3.11 (4.2%) stddev-sans-first:3.11 03:29:24 INFO - PROCESS | 5032 | Values: 77.0 72.0 81.0 71.0 74.0 72.0 72.0 72.0 74.0 76.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:123.00 stddev:13.01 (10.6%) stddev-sans-first:13.57 03:29:24 INFO - PROCESS | 5032 | Values: 117.0 116.0 126.0 125.0 116.0 124.0 117.0 116.0 159.0 122.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.50 Median:150.50 stddev:1.72 (1.1%) stddev-sans-first:1.81 03:29:24 INFO - PROCESS | 5032 | Values: 151.0 150.0 149.0 149.0 153.0 153.0 148.0 152.0 150.0 150.0 03:29:24 INFO - PROCESS | 5032 | 03:29:24 INFO - PROCESS | 5032 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.62 (2.6%) stddev-sans-first:1.66 03:29:24 INFO - PROCESS | 5032 | Values: 63.0 62.0 62.0 61.0 65.0 61.0 63.0 61.0 59.0 61.0 03:29:24 INFO - PROCESS | 5032 | -------- Summary: end -------- 03:29:24 INFO - PROCESS | 5032 | 03:29:25 INFO - TEST-INFO | 5032: exit 0 03:29:25 INFO - TEST-OK | kraken | took 60145ms 03:29:25 INFO - SUITE-END | took 1174s 03:29:25 INFO - Completed test suite (00:19:34) 03:29:25 INFO - javascript benchmark 03:29:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3285.1294406982156, 3299.884504042359, 3301.5873015873017, 3323.6676332366783, 3339.5324654548367, 5630.887382252357, 5725.169850371693, 5739.25389699339, 5770.278715160605, 5791.276090488684, 5710.885782284352, 5790.36306006339, 5791.536677065835, 5793.102069179274, 5794.536437085034, 5062.898742025157, 5125.564327032207, 5199.194124910636, 5207.036698210824, 5258.290130832327, 5180.974095129519, 5183.637145399824, 5194.71429071401, 5198.350206224222, 5239.083160446926, 5194.480551944813, 5194.92207616886, 5250.763715632786, 5250.973745131269, 5257.13257312543, 5296.947030529704, 5308.044551980642, 5309.230161626542, 5315.681059136464, 5320.76056577455, 5076.974615126937, 5077.873053173664, 5133.717645529495, 5207.609429292818, 5214.374275086976, 5637.182608521783, 5653.265075540154, 5677.176809362661, 5698.145278208261, 5703.74333155009, 6172.537059720494, 6179.165812615284, 6184.257889053342, 6186.9381306186815, 6189.226346706661, 5992.820215393545, 6103.84740381494, 6104.175936248594, 6105.8791806896725, 6233.34549872266, 5760.107183386582, 5769.451902069339, 5783.479486846204, 5787.971060144757, 5791.507721843606, 6282.874342513124, 6294.087357333161, 6306.968465157736, 6309.022101574264, 6313.053042043638, 5727.971360143256, 5976.103584462278, 6027.9095813562835, 6038.245219347582, 6100.561447286338, 6186.721597528122, 6188.257409110935, 6194.318624951252, 6195.039768835838, 6195.31851496335, 6083.543734219951, 6136.631802091889, 6142.802153580009, 6154.692265386713, 6161.0, 5891.852703682443, 5990.850228744229, 6025.15647809307, 6029.306629737549, 6135.723892424851, 4584.266517357207, 4615.976920115378, 4620.5411777634745, 4621.145088158702, 4684.953150468452, 4126.793660316972, 4138.461999939988, 4144.70987030905, 4162.0, 4166.729162604421, 5611.579131565149, 5666.320041595035, 5736.6844823534275, 5746.9425305747245, 5830.650160990354, 6077.8480537986015, 6083.300420451616, 6087.756489740449, 6094.39056094387, 6097.237845269341, 5730.74211660468, 5733.254676892061, 5735.082386818089, 5737.967165910093, 5740.196372507852, 5016.272640467112, 5023.623228257822, 5044.873878153002, 5048.1418158913075, 5130.358705161855, 6317.778877739257, 6327.2090988625505, 6327.462165715873, 6331.2085989251345, 6331.556791024676, 6076.118962750288, 6104.236970378703, 6110.114033465212, 6149.508039356751, 6151.50787936955, 6113.506711826627, 6119.0, 6161.44546990755, 6166.352532983972, 6262.185915830913, 5971.970140149271, 5972.343042265435, 5973.790917317873, 5975.163477113295, 5999.040153575582, 6051.667158306335, 6056.152138700673, 6061.605995610271, 6062.272527296753, 6064.969675151596, 5873.442023007808, 5971.970140149445, 5998.0, 6023.066424704178, 6107.450329470369, 5973.55198360116, 6005.309389420273, 6009.06859436788, 6014.609050411885, 6021.42796434338, 5936.910946335895, 5978.461938425563, 5983.521318294439, 5987.5808693391045, 5996.2504686914135, 5803.7678492861505, 5826.271716035496, 5967.671778052249, 5992.0, 6051.727672254847, 5965.612235204698, 5966.731497082555, 5971.193888824953, 5973.163757073926, 5992.520598352208], "value": 5626.0650644859215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.2916558461014, 622.6855438003807, 623.5853157650162, 652.4030512081447, 655.0534477679755, 34584.82707586462, 48879.51120488794, 49451.258231126565, 49729.0, 49792.004159916825, 50932.74533627336, 51327.48672513274, 51439.74280128603, 51450.0, 51616.74191629037, 8065.879011814798, 9190.540472976341, 9441.480718560477, 9441.669541566047, 11039.0, 10861.294015888981, 11353.716157096056, 11361.545538178505, 11361.829572556418, 11363.659090227306, 11433.0, 11470.885291147066, 11472.139589530818, 11472.942635286854, 11482.196246262765, 11326.15053870963, 11341.206115571913, 11342.262752921033, 11377.317360958368, 11408.942955285254, 11185.217034807485, 11240.831387529193, 11243.212975091747, 11260.831087533694, 11261.43692815356, 47133.76433117847, 47557.28664070042, 47632.0, 47667.0, 47678.76160619209, 3781.470594116826, 3824.063104539378, 3894.941575876364, 3905.243630106006, 3944.1914407546383, 36498.452523212174, 36583.4512482313, 36613.633863661285, 36677.81661091677, 36881.631183688085, 4695.225287827504, 4703.600193983515, 4731.976340118277, 4751.287306903958, 4833.371661683994, 386522.0, 388520.0, 389234.0, 390018.0, 392530.0, 70268.64865675638, 70331.0, 70429.64785176145, 70807.0, 70846.0, 40126.39810402846, 41199.1760164801, 41204.1759164815, 43238.0, 43377.0, 19798.40604781859, 19983.600327993357, 19990.800091999186, 20002.29991950304, 20003.399898003085, 13361.331933403291, 14769.778453323208, 15073.698526029419, 15139.091654500766, 15151.393944242327, 21811.890940545196, 21858.23496177658, 21893.12427502914, 22003.559928801333, 22095.889520552293, 7273.599952002691, 7295.161056478467, 7499.475036747375, 7610.733624323122, 7702.152763196044, 1012.1548506996593, 1085.310827624464, 1103.3214573037524, 1128.368113856243, 1199.4527060092382, 32692.673073269438, 35080.47379289313, 35615.10962225965, 35957.46063809045, 36259.637403625624, 1180.7815554122344, 1193.2065176657425, 1196.3539688568076, 1268.2137075013366, 1297.1438850358718, 18526.258949641862, 18705.345312914254, 18749.0, 18750.906245468686, 18762.34331798408, 611385.0, 618977.0, 619048.0, 619986.0, 621521.0, 68703.3129668697, 69197.30802692009, 69297.30702692909, 69315.65342173257, 69399.65300173467, 1280.3406245783428, 1416.2226405860579, 1421.6161636358124, 1462.1519518679354, 1478.578605097542, 35324.29351412906, 37320.253594928494, 37650.43524347192, 37978.0, 38107.23785524329, 57504.0, 58220.4177958215, 58268.0, 58303.0, 58351.0, 450050.0, 453687.0, 458266.0, 460309.0, 461143.0, 31175.37649246957, 32294.35411291808, 32518.674813251564, 34037.319253614296, 34109.65890341164, 54876.45123548713, 55543.16685249643, 55782.0, 56078.0, 56694.0, 45020.54979450295, 46586.0, 47019.05961880675, 47182.05635887332, 48201.0, 45120.514407812916, 46134.76932615315, 47238.0, 47441.52558474371, 48763.0, 46759.53240467688, 47225.0, 48147.75926120487, 48521.02957940751, 48762.51237487722, 39173.0, 41633.58366416418, 41739.582604174786, 42091.50586633371, 42290.154196916505, 30610.387792244477, 31221.8438907804, 31445.908977866202, 31630.209244769067, 31690.0], "value": 22591.941436282235, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.9176972835704, 405.393936065582, 423.8516519218274, 428.66135752755315, 430.97629630370335, 15098.0, 20505.692414613757, 20511.282105126324, 20663.38009859703, 21223.787762122374, 20208.797912020877, 20244.69632955503, 20254.0, 20298.492537686565, 20545.075471603777, 5745.080787074069, 5860.736266867991, 5893.941060589382, 5961.344252132262, 5985.042393217086, 5782.537397008229, 5825.650460972355, 5825.8834823303505, 5826.9708651456685, 5827.184194212792, 5613.578981576377, 5805.042168042289, 5834.5915785895, 5919.112133180014, 5928.081147422135, 5880.529557635379, 6042.123892035652, 6393.456556192729, 6461.998390249519, 6476.287608363076, 5874.618149820248, 5879.588428809985, 5931.08068249422, 5936.5250779937505, 5942.8811423771285, 23991.7600823993, 24009.0, 24623.876880615662, 24705.38236544083, 24957.376065598324, 2336.906523739048, 2414.480886609387, 2466.4203912080648, 2615.5422801009745, 2836.702146274653, 10654.893451065467, 10716.839247411295, 10793.298435601739, 10831.83752243717, 10973.451327433597, 1970.1823743146576, 1976.8637404447509, 2008.0963566395035, 2051.148773259096, 2090.174381119449, 84951.57524212402, 85259.72110418411, 86754.13245867523, 86809.0, 86888.0, 11336.94331528337, 11503.53985840557, 11713.1800773945, 11823.113266505046, 11860.0, 16837.91581042087, 16856.241469133674, 16875.662486750196, 16910.57723556921, 16933.238004289837, 8763.824723505622, 8844.73465796027, 8918.063603321687, 8935.28517718581, 9120.49837258957, 8653.870191947126, 8776.736697899074, 8824.955875220583, 8884.73345799614, 9011.143941325563, 5009.874753131201, 5056.57019153376, 5083.542481176638, 5098.164660722137, 5101.285819985205, 2032.8576999609886, 2092.780258072911, 2146.527763891941, 2153.4078128514784, 2174.5433458973794, 976.5898322704339, 1045.5033858917106, 1053.6154303679255, 1055.6147006342783, 1078.5739632844982, 20528.281510147073, 21090.156393744386, 21133.78866211349, 21190.682139767916, 21275.255366062112, 1055.7360659835042, 1066.919981001428, 1067.2102644043352, 1081.0648788797632, 1117.7485065860117, 11580.131490138274, 11766.764664706658, 11914.46384912681, 11947.880521194851, 11951.641450756491, 477134.0, 484293.0, 486981.0, 506086.0, 540503.0, 94483.0, 95638.52180739191, 96831.0, 98520.50739746398, 106592.0, 1048.533402635835, 1055.4669891704793, 1084.2464487181333, 1090.0135377483232, 1092.2026920348032, 16074.839251607335, 18164.636707266043, 18172.90913545424, 18251.178696958403, 18716.625667486845, 45636.0, 45859.77070114628, 46012.30981535347, 46406.53593464022, 47664.2850357238, 62646.37353626588, 63607.68196158989, 64587.354126458136, 65100.674496627216, 66185.66907165434, 8754.124587541195, 8774.736757897273, 8955.41789783662, 9023.954880225558, 9115.635374585208, 10689.465526723789, 13420.731585368434, 13819.930900345435, 14136.43454261818, 14179.929100354433, 10896.673099807018, 11232.55069797199, 11447.084233261152, 11534.250273732181, 12110.849469300403, 11581.0, 11849.940750296193, 12068.456919438822, 12156.756864862477, 12228.266304021787, 11094.833577496505, 11348.602798901999, 11443.942780286045, 11747.236429632047, 12112.0, 8383.832323353621, 8400.495970241805, 8510.74467765968, 8590.742277731679, 8797.692080777142, 6683.0, 10820.134389248684, 10910.399928004035, 11461.885381146081, 11571.19001669875], "value": 9677.691474839956, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.6156237815783, 1558.3065535836554, 1597.08167803513, 1779.8932064076157, 1913.0912816412197, 8110.756677299683, 9380.85928711069, 9657.661981830637, 9663.323567350279, 10044.0, 9695.951520242406, 9803.509824508781, 9804.019598040193, 9805.607775688964, 9814.950925245364, 2572.5626643470655, 2598.142612937726, 2600.4149066460086, 2603.2060221632396, 2603.7916966642624, 2490.302715239736, 2587.6118582212725, 2592.8703564821685, 2595.4809038192343, 2595.5717306644474, 2522.0794410040385, 2564.9358766030814, 2571.90998315059, 2579.7291284415055, 2581.7805486533666, 2509.234683421565, 2519.2694118705554, 2524.0787112703915, 2527.835690680109, 2537.314924970266, 2448.4735781807, 2453.877306134686, 2458.483718419134, 2465.0386349323776, 2511.510255500178, 9625.326227164102, 9759.316847820655, 9808.411495310233, 9818.361806482555, 9822.410655360629, 5686.09022556389, 5971.0744834550715, 5992.370801065869, 6013.51891848651, 6033.78881739137, 3669.688076513473, 3758.699304055669, 3784.489093972306, 3806.942895856537, 3821.2739579479808, 1191.4987116233522, 1211.9879900283274, 1228.2753175626333, 1230.5262473947505, 1278.0861683895967, 13911.652208694763, 13965.092269002482, 13974.650633734238, 14022.228777417238, 14056.226907520184, 4856.368672072678, 4986.401631804134, 5047.343845300087, 5068.265101560253, 5101.183810590288, 10768.46157692128, 10769.623063192907, 10789.406582637874, 10801.081908037748, 10807.945960270148, 5724.656520608777, 5725.541956643458, 5726.055200891889, 5728.598998070179, 5729.312482502043, 4704.08270387278, 4751.501092385319, 5302.522772950376, 5303.893922121536, 5331.81338653153, 4278.336857787048, 4296.892577685583, 4359.2807186813825, 4386.583274588909, 4397.1865204937185, 1257.69186549296, 1279.8821632596205, 1288.4137717338588, 1295.054610134588, 1295.8639342868862, 1061.830107182847, 1076.3488089705772, 1087.543231842628, 1088.2871719024051, 1094.5402930769096, 6391.616503009835, 6550.3449655035065, 6624.205095388585, 6796.626185559743, 6807.965960170168, 479.966402351832, 483.8596806925952, 488.9291052797324, 498.11833055491576, 513.4454788828132, 4535.818567257273, 4542.750148741851, 4603.401557797465, 4647.140279048387, 4665.6734028617675, 12202.633920982385, 12580.622581322576, 12680.365981700877, 12693.238405695687, 12697.492100315883, 11430.657080287605, 11990.820137697941, 12012.759744805055, 12564.246145231133, 12600.81098783519, 8855.51294678786, 9039.457632541937, 9048.185663290336, 9052.683156089506, 9056.728298151065, 6612.8346791330605, 6889.483288753464, 6920.065791118134, 6960.582365058112, 6972.546784458994, 11130.888691112985, 11133.94433027862, 11413.144014199135, 11489.82765258505, 11532.82700759506, 9440.527973601429, 11400.543978240778, 11507.539698412305, 11774.882251177378, 11847.585334513251, 8799.252063574411, 9024.142706442877, 9094.090590941118, 9110.772230694285, 9128.726138215865, 9500.287478439282, 9665.0, 9668.516574171123, 9675.70972870815, 9677.516124193622, 8488.787780305542, 8595.269402100892, 8610.698625548075, 8755.781105472159, 8853.0, 8623.482591044558, 8675.175858293453, 8676.305895528469, 8698.21716045559, 8855.867161992446, 7533.698652053857, 7603.201663825219, 7669.7315593953945, 7681.0, 7686.692532298645, 5491.395946445808, 5554.416786237549, 5575.832725018256, 5627.0, 5872.618279811799, 5506.614536982373, 5602.047651899105, 5646.858828529155, 5741.110127930177, 5827.213326201078], "value": 5018.38652200208, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.69431428771236, 333.8013881740364, 334.8275638046408, 343.157548219122, 350.23298975244205, 5651.265335506384, 5670.036093864042, 5675.886482270357, 5683.943160568393, 5701.458361455665, 5575.553955683546, 5600.915986260209, 5608.719564021803, 5624.831255062355, 5629.577781666381, 4207.0, 4219.156168766243, 4378.299472084467, 4402.273624851896, 4403.713758605696, 4366.148601022786, 4375.846845360413, 4378.737275763448, 4403.669724770639, 4431.8670439886855, 4296.334068219431, 4348.369486424483, 4352.216601011816, 4360.23695853226, 4397.934030989538, 4067.3085575452096, 4275.850345237917, 4332.870013899556, 4381.715188512737, 4418.536053714346, 3918.314294998364, 3922.4704664870164, 3930.508686414198, 3935.6457918787446, 3942.172143849795, 5449.4005659377835, 5470.671759694431, 5505.834824955257, 5526.309211348582, 5528.5024347808485, 4758.738269395182, 4932.7040377577105, 4965.702057876539, 4971.254311853251, 4990.800367985276, 5813.912791308091, 5817.0, 5819.592628516006, 5838.970805145947, 5844.970775146097, 4144.647704945083, 4237.0, 4240.512341080785, 4254.7234429762275, 4271.893202669927, 6009.909851352233, 6058.39416058395, 6068.484178844804, 6077.301110372319, 6078.848028799315, 4289.935650965238, 4290.2706539888295, 4293.97853010733, 4302.9569704303185, 4322.22200003996, 4190.643795277375, 4326.15639950213, 4326.891827704269, 4352.260115780262, 4362.236608593484, 4873.610111191097, 4928.531789479994, 4944.3572335596145, 4964.503549645079, 4989.151844186497, 5863.149843272701, 5900.734466948998, 5909.172715819789, 5961.4038596140035, 5962.731677074456, 1498.423107103773, 1504.2253239581623, 1521.7413039783264, 1527.1676936069646, 1529.915854627984, 954.0650162840456, 960.9135177833889, 974.6783561424713, 982.4547376206132, 987.9851802222972, 5564.5254007688, 5692.1461780733225, 5724.627899186539, 5730.398308177581, 5732.742026608729, 6012.368701286389, 6049.304330002106, 6049.879002419927, 6061.363556826558, 6073.51411887048, 5598.992181407304, 5610.691411972381, 5643.661380317112, 5699.2590963174525, 5735.279416175131, 3169.683031696858, 3202.727768139734, 3213.826953162079, 3217.613886333655, 3222.8227447490613, 6005.549583781234, 6150.261968563801, 6220.844478887973, 6258.968705156445, 6261.812145635638, 6069.848253793691, 6079.635221886612, 6090.695465226721, 6104.8779024420155, 6109.7861574844665, 6179.443850053517, 6188.9885787119365, 6191.318954914956, 6236.501079913505, 6239.500839932885, 5844.129878438859, 5938.228030356199, 5968.46283834457, 5975.760969561169, 6016.789412370546, 5901.439363260483, 5910.231669882888, 5932.911006334822, 5976.462118409364, 5986.16193732869, 5942.762289508371, 6118.684482836212, 6152.292486364126, 6210.472109870711, 6214.9067763984485, 4204.936925946175, 4206.726562773532, 4208.22147902639, 4218.282891908321, 4231.365295205744, 5955.6724380157775, 6050.334463208956, 6109.236345456818, 6130.295016073031, 6136.7852125175405, 5770.432157689787, 5901.0, 5909.438603332676, 5910.497607703222, 5946.375630558902, 5859.531237500904, 5867.794627188028, 5880.441358070976, 5882.205902203148, 5889.793857214977, 5540.501354878081, 5761.971190144022, 5766.2989418120915, 5840.853978650398, 5872.588918775816, 5858.238429004202, 5888.205092312654, 5894.587378883437, 5913.255589601164, 5916.674582897982, 5766.94233057664, 5777.45114214149, 5780.450857168562, 5784.7107644618445, 5791.971040144772], "value": 4474.222852364104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.6302427436714, 832.3507664022057, 846.2722059029234, 849.3884403229674, 857.5712143928037, 16500.66998660026, 17626.383076592323, 17674.823251767477, 17689.734653980184, 17690.38083667072, 14725.410983560645, 14903.850961490381, 14919.030263032922, 14935.626609334771, 14944.327505262263, 692.9930700693004, 843.7679638099511, 865.9350548708841, 870.0951010948606, 873.6898401067625, 879.9560021998874, 881.1452890696015, 881.8280435315116, 882.3206131278912, 882.7837179890936, 952.6475204174466, 953.5947222430474, 954.3939598355025, 955.0926619711267, 955.2692190474293, 727.7089164334283, 750.2497502497503, 753.8567672142275, 883.1830556735059, 947.4741518457254, 681.7136802542943, 683.7094234950176, 683.8016975077221, 683.8222062263798, 684.1755684400335, 15891.36434542617, 15953.760693589606, 15991.440299589458, 16006.759898601531, 16046.438374656947, 14936.0, 15185.392584296615, 15307.387704491919, 15337.0, 15643.296051677702, 14045.64885877851, 14101.929490352482, 14239.216843073627, 14462.0, 14510.27448627575, 4619.422572178478, 4670.556297151799, 4716.0, 4768.785404656799, 4786.497417771118, 83129.58435207845, 84805.0, 92394.5380273101, 92751.53624231835, 92957.5352123235, 11349.886501135048, 11750.23623464472, 11773.529058837721, 11914.702132446759, 12113.15207935436, 24311.0, 24322.756772432404, 24335.14826981047, 24351.51296974086, 24357.26928192157, 8622.741317760478, 8861.601227944759, 9004.729858104267, 9062.486064383822, 9293.256539476826, 4835.2505361669, 4947.802087916443, 5107.821226257138, 5160.612954028463, 5225.069546065243, 3831.099691572478, 3985.940210896839, 3990.700697447703, 3996.140829721624, 3998.520177578709, 662.2615783401473, 667.9131712877295, 671.6775947545206, 687.3847906123991, 689.069755829626, 720.6396801599201, 758.0978635423828, 761.7219714804056, 767.7888580640257, 774.6591499740097, 9465.337426380087, 10070.546825392876, 10082.546285417175, 10241.897581024243, 10267.229957753198, 581.7905554000556, 659.3768888400512, 709.0392518137952, 729.941604671625, 793.5278509287081, 4720.905581888343, 4865.9026819463415, 4931.624101757714, 4964.925526117111, 5065.240213967861, 71675.64162179155, 98530.0, 98714.0, 128750.0, 135832.0, 97610.0, 112159.0, 114987.0, 118738.40630796964, 120366.0, 67841.32158678448, 77936.0, 83162.0, 105587.47206264074, 110240.0, 10278.537465813906, 10758.73103172427, 10809.783804324026, 11275.943620281847, 11282.435878205893, 58470.0, 59978.40021599903, 60379.09431358621, 61263.38736612577, 62461.68769156307, 68944.6552767233, 77321.2267877314, 78155.60922195352, 83287.5835620818, 83794.0, 14933.850661493247, 15078.623034424227, 15423.69152616919, 15935.0, 16206.91896540557, 15582.376704932129, 15684.372625094868, 16443.753343699616, 16551.172441377643, 17069.06120163403, 14500.419983200554, 15458.304376303318, 15466.690666186389, 15966.20168991569, 16180.838191617933, 13644.65888352799, 13663.0, 13713.17720936747, 14509.347079381241, 15017.174055427056, 15118.924405377902, 15184.46854360092, 15414.922925385301, 15446.382144714085, 16239.350425982828, 11646.534138634359, 12221.450034748636, 12270.4478298475, 12323.568675096329, 12976.545820896223, 12275.559543392439, 12335.69160770952, 12523.0, 12685.746285074063, 12876.549320773727], "value": 8207.684560036696, "unit": "score"}], "name": "dromaeo_css", "value": 7808.712365153854, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 109.0, 111.0, 112.0, 109.0, 111.0, 110.0, 110.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 114.0, 164.0, 116.0, 117.0, 137.0, 115.0, 154.0, 115.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 123.0, 75.0, 76.0, 74.0, 75.0, 113.0, 75.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 121.0, 85.0, 84.0, 83.0, 121.0, 82.0, 83.0, 122.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 52.0, 51.0, 53.0, 53.0, 52.0, 50.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 63.0, 63.0, 62.0, 61.0, 61.0, 62.0, 63.0, 63.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 81.0, 71.0, 74.0, 72.0, 72.0, 72.0, 74.0, 76.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 126.0, 125.0, 116.0, 124.0, 117.0, 116.0, 159.0, 122.0], "value": 123.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 149.0, 149.0, 153.0, 153.0, 148.0, 152.0, 150.0, 150.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 61.0, 65.0, 61.0, 63.0, 61.0, 59.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1458.3999999999999, "alertThreshold": 2.0}]} 03:29:25 INFO - Return code: 0 03:29:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:29:25 INFO - # TBPL SUCCESS # 03:29:25 INFO - Running post-action listener: _resource_record_post_action 03:29:25 INFO - [mozharness: 2016-08-08 10:29:25.417180Z] Finished run-tests step (success) 03:29:25 INFO - Running post-run listener: _resource_record_post_run 03:29:25 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 327184384; Read time: 52; Write time: 375552 03:29:25 INFO - TinderboxPrint: CPU usage
13.0% 03:29:25 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 52 03:29:25 INFO - TinderboxPrint: I/O write bytes / time
327,184,384 / 375,552 03:29:25 INFO - TinderboxPrint: CPU idle
8,273.8 (87.0%) 03:29:25 INFO - TinderboxPrint: CPU user
1,185.0 (12.5%) 03:29:25 INFO - TinderboxPrint: Swap in / out
0 / 0 03:29:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79015936; Read time: 0; Write time: 105852 03:29:25 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 248168448; Read time: 52; Write time: 269700 03:29:26 INFO - Running post-run listener: _upload_blobber_files 03:29:26 INFO - Blob upload gear active. 03:29:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:29:26 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 03:29:26 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'] 03:29:26 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 03:29:26 INFO - (blobuploader) - INFO - Open directory for files ... 03:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:31 INFO - (blobuploader) - INFO - Done attempting. 03:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:31 INFO - (blobuploader) - INFO - Done attempting. 03:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:32 INFO - (blobuploader) - INFO - Done attempting. 03:29:32 INFO - (blobuploader) - INFO - Iteration through files over. 03:29:32 INFO - Return code: 0 03:29:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:29:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:29:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e106ae434da396f4d99a4e1eeb4f511a1d40a1f97c6399184f81edadc0748ae6dc7278d79de229000a70cde8f8c7bab5e2c89b6c2f9771bc0ffc1defe4e158b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93bfdd3700d1e95bf14e79b945c09f5ccce0c4036be36f7418d45f03dc1cb041d002795bb7e6b3f5722695838f0c63506ca627711c87bb7eeb6f964189d415ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e663d5ce3c7dd396cd34a66f07c1e37a3c2c9e2da0bfd129bbcc4d62975b155f45014950f2799d22f432631c47190a1b44b74308e5ec1e2ff1fb29a3f531034c"} 03:29:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:29:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:29:32 INFO - Contents: 03:29:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e106ae434da396f4d99a4e1eeb4f511a1d40a1f97c6399184f81edadc0748ae6dc7278d79de229000a70cde8f8c7bab5e2c89b6c2f9771bc0ffc1defe4e158b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93bfdd3700d1e95bf14e79b945c09f5ccce0c4036be36f7418d45f03dc1cb041d002795bb7e6b3f5722695838f0c63506ca627711c87bb7eeb6f964189d415ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e663d5ce3c7dd396cd34a66f07c1e37a3c2c9e2da0bfd129bbcc4d62975b155f45014950f2799d22f432631c47190a1b44b74308e5ec1e2ff1fb29a3f531034c"} 03:29:32 INFO - Running post-run listener: copy_logs_to_upload_dir 03:29:32 INFO - Copying logs to upload dir... 03:29:32 INFO - mkdir: /builds/slave/test/build/upload/logs 03:29:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.921154 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-08 03:29:31.307398) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 03:29:31.312875) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682 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/7e106ae434da396f4d99a4e1eeb4f511a1d40a1f97c6399184f81edadc0748ae6dc7278d79de229000a70cde8f8c7bab5e2c89b6c2f9771bc0ffc1defe4e158b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93bfdd3700d1e95bf14e79b945c09f5ccce0c4036be36f7418d45f03dc1cb041d002795bb7e6b3f5722695838f0c63506ca627711c87bb7eeb6f964189d415ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e663d5ce3c7dd396cd34a66f07c1e37a3c2c9e2da0bfd129bbcc4d62975b155f45014950f2799d22f432631c47190a1b44b74308e5ec1e2ff1fb29a3f531034c"} build_url:https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011430 build_url: 'https://queue.taskcluster.net/v1/task/ehB5u4Y9Q4C5q1EdiwTdVw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e106ae434da396f4d99a4e1eeb4f511a1d40a1f97c6399184f81edadc0748ae6dc7278d79de229000a70cde8f8c7bab5e2c89b6c2f9771bc0ffc1defe4e158b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93bfdd3700d1e95bf14e79b945c09f5ccce0c4036be36f7418d45f03dc1cb041d002795bb7e6b3f5722695838f0c63506ca627711c87bb7eeb6f964189d415ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e663d5ce3c7dd396cd34a66f07c1e37a3c2c9e2da0bfd129bbcc4d62975b155f45014950f2799d22f432631c47190a1b44b74308e5ec1e2ff1fb29a3f531034c"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 03:29:31.365760) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:29:31.366125) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470649496.14173-1046976682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:29:31.466036) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 03:29:31.466399) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 03:29:31.466736) ========= ========= Total master_lag: 0.24 =========