builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1470652224.67 results: success (0) buildid: 20160808004011 builduid: ab19c83038074094a2223fc8a0a4f65f revision: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.673555) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.674476) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.675029) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.691125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.691739) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-08 03:30:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-08-08 03:30:25 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132141 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.839507) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.839866) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018795 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.892145) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:24.892926) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-08 03:30:26,043 truncating revision to first 12 chars 2016-08-08 03:30:26,043 Setting DEBUG logging. 2016-08-08 03:30:26,043 attempt 1/10 2016-08-08 03:30:26,043 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:30:26,425 unpacking tar archive at: mozilla-aurora-93a54e1c76c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.727624 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:25.639550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:25.639911) ========= script_repo_revision: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:25.640332) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:25.640646) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 03:30:25.652060) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-08 03:30:25.652373) ========= /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=78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:30:26 INFO - MultiFileLogger online at 20160808 03:30:26 in /builds/slave/test 03:30:26 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:30:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:30:26 INFO - {'append_to_log': False, 03:30:26 INFO - 'base_work_dir': '/builds/slave/test', 03:30:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:30:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:30:26 INFO - 'branch': 'Mozilla-Aurora', 03:30:26 INFO - 'buildbot_json_path': 'buildprops.json', 03:30:26 INFO - 'config_files': ('talos/linux_config.py',), 03:30:26 INFO - 'default_actions': ('clobber', 03:30:26 INFO - 'read-buildbot-config', 03:30:26 INFO - 'download-and-extract', 03:30:26 INFO - 'populate-webroot', 03:30:26 INFO - 'create-virtualenv', 03:30:26 INFO - 'install', 03:30:26 INFO - 'run-tests'), 03:30:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:30:26 INFO - 'download_minidump_stackwalk': True, 03:30:26 INFO - 'download_symbols': 'ondemand', 03:30:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:30:26 INFO - 'tooltool.py': '/tools/tooltool.py', 03:30:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:30:26 INFO - '/tools/misc-python/virtualenv.py')}, 03:30:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:30:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:30:26 INFO - 'installer_path': 'installer.exe', 03:30:26 INFO - 'log_level': 'info', 03:30:26 INFO - 'log_name': 'talos', 03:30:26 INFO - 'log_to_console': True, 03:30:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:30:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:30:26 INFO - 'opt_config_files': (), 03:30:26 INFO - 'pip_index': False, 03:30:26 INFO - 'sps_profile': False, 03:30:26 INFO - 'sps_profile_interval': 0, 03:30:26 INFO - 'suite': 'dromaeojs', 03:30:26 INFO - 'system_bits': '32', 03:30:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:30:26 INFO - 'title': 'talos-linux64-ix-019', 03:30:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:30:26 INFO - 'use_talos_json': True, 03:30:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:30:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:30:26 INFO - 'work_dir': 'build'} 03:30:26 INFO - [mozharness: 2016-08-08 10:30:26.884914Z] Running clobber step. 03:30:26 INFO - Running pre-action listener: _resource_record_pre_action 03:30:26 INFO - Running main action method: clobber 03:30:26 INFO - rmtree: /builds/slave/test/build 03:30:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:30:27 INFO - Running post-action listener: _resource_record_post_action 03:30:27 INFO - [mozharness: 2016-08-08 10:30:27.343147Z] Finished clobber step (success) 03:30:27 INFO - [mozharness: 2016-08-08 10:30:27.343248Z] Running read-buildbot-config step. 03:30:27 INFO - Running pre-action listener: _resource_record_pre_action 03:30:27 INFO - Running main action method: read_buildbot_config 03:30:27 INFO - Using buildbot properties: 03:30:27 INFO - { 03:30:27 INFO - "project": "", 03:30:27 INFO - "product": "firefox", 03:30:27 INFO - "script_repo_revision": "production", 03:30:27 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:30:27 INFO - "repository": "", 03:30:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:30:27 INFO - "buildid": "20160808004011", 03:30:27 INFO - "stage_platform": "linux64-pgo", 03:30:27 INFO - "basedir": "/builds/slave/test", 03:30:27 INFO - "nightly_build": "True", 03:30:27 INFO - "buildnumber": 665, 03:30:27 INFO - "slavename": "talos-linux64-ix-019", 03:30:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:30:27 INFO - "platform": "ubuntu64_hw", 03:30:27 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:30:27 INFO - "branch": "mozilla-aurora", 03:30:27 INFO - "repo_path": "releases/mozilla-aurora", 03:30:27 INFO - "revision": "93a54e1c76c1602c65d2ba7bd1b6f20a997765e5", 03:30:27 INFO - "pgo_build": "True", 03:30:27 INFO - "builduid": "ab19c83038074094a2223fc8a0a4f65f", 03:30:27 INFO - "slavebuilddir": "test" 03:30:27 INFO - } 03:30:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:30:27 INFO - Running post-action listener: _resource_record_post_action 03:30:27 INFO - [mozharness: 2016-08-08 10:30:27.345812Z] Finished read-buildbot-config step (success) 03:30:27 INFO - [mozharness: 2016-08-08 10:30:27.345901Z] Running download-and-extract step. 03:30:27 INFO - Running pre-action listener: _resource_record_pre_action 03:30:27 INFO - Running main action method: download_and_extract 03:30:27 INFO - mkdir: /builds/slave/test/build/tests 03:30:27 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:30:27 INFO - https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:30:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:30:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:30:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:30:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:30:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:30:27 INFO - retry: Failed, sleeping 30 seconds before retrying 03:30:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:30:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:30:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:30:57 INFO - retry: Failed, sleeping 60 seconds before retrying 03:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:31:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:31:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:31:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:31:57 INFO - Caught exception: HTTP Error 404: Not Found 03:31:57 INFO - Caught exception: HTTP Error 404: Not Found 03:31:57 INFO - Caught exception: HTTP Error 404: Not Found 03:31:57 INFO - trying https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:31:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:31:58 INFO - Downloaded 1361 bytes. 03:31:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:31:58 INFO - Using the following test package requirements: 03:31:58 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:31:58 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:31:58 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:31:58 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'jsshell-linux-x86_64.zip'], 03:31:58 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:31:58 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:31:58 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:31:58 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:31:58 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:31:58 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:31:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:31:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:58 INFO - https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:31:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:31:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:31:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:31:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:31:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:31:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:31:58 INFO - retry: Failed, sleeping 30 seconds before retrying 03:32:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:32:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:32:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:32:28 INFO - retry: Failed, sleeping 60 seconds before retrying 03:33:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:33:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:33:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:33:28 INFO - Caught exception: HTTP Error 404: Not Found 03:33:28 INFO - Caught exception: HTTP Error 404: Not Found 03:33:28 INFO - Caught exception: HTTP Error 404: Not Found 03:33:28 INFO - trying https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:33:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:33:29 INFO - Downloaded 21766465 bytes. 03:33:29 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:33:29 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:33:30 INFO - Return code: 0 03:33:30 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:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:30 INFO - https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:33:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:33:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:33:30 INFO - retry: Failed, sleeping 30 seconds before retrying 03:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:34:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:34:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:34:00 INFO - retry: Failed, sleeping 60 seconds before retrying 03:35:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:35:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:35:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:35:00 INFO - Caught exception: HTTP Error 404: Not Found 03:35:00 INFO - Caught exception: HTTP Error 404: Not Found 03:35:00 INFO - Caught exception: HTTP Error 404: Not Found 03:35:00 INFO - trying https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:35:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:35:01 INFO - Downloaded 21766465 bytes. 03:35:01 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:35:01 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:35:02 INFO - Return code: 0 03:35: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:35:02 INFO - https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:35:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:35:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:35:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:35:02 INFO - retry: Failed, sleeping 30 seconds before retrying 03:35:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:35:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:35:32 INFO - retry: Failed, sleeping 60 seconds before retrying 03:36:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:36:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:36:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:36:32 INFO - Caught exception: HTTP Error 404: Not Found 03:36:32 INFO - Caught exception: HTTP Error 404: Not Found 03:36:32 INFO - Caught exception: HTTP Error 404: Not Found 03:36:32 INFO - trying https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:36:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:36:33 INFO - Downloaded 14413723 bytes. 03:36:33 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:36:33 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:36:34 INFO - Return code: 0 03:36: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:36:34 INFO - https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:36:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:36:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:36:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:36:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:36:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:36:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:36:34 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:37:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:37:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:04 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:38:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:38:04 INFO - Caught exception: HTTP Error 404: Not Found 03:38:04 INFO - Caught exception: HTTP Error 404: Not Found 03:38:04 INFO - Caught exception: HTTP Error 404: Not Found 03:38:04 INFO - trying https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:38:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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:38:05 INFO - Downloaded 63756384 bytes. 03:38:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:05 INFO - mkdir: /builds/slave/test/properties 03:38:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:38:05 INFO - Writing to file /builds/slave/test/properties/build_url 03:38:05 INFO - Contents: 03:38:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:06 INFO - Running post-action listener: _resource_record_post_action 03:38:06 INFO - Running post-action listener: set_extra_try_arguments 03:38:06 INFO - [mozharness: 2016-08-08 10:38:06.232547Z] Finished download-and-extract step (success) 03:38:06 INFO - [mozharness: 2016-08-08 10:38:06.232783Z] Running populate-webroot step. 03:38:06 INFO - Running pre-action listener: _resource_record_pre_action 03:38:06 INFO - Running main action method: populate_webroot 03:38:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:38:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:38:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:38:06 INFO - '--tppagecycles', 03:38:06 INFO - '7'], 03:38:06 INFO - 'tests': ['tsvgm']}}, 03:38:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:38:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:38:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:38:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:38:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:38:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:38:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['damp', 'tps']}, 03:38:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'tests': ['damp', 'tps']}, 03:38:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['dromaeo_dom']}, 03:38:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:38:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['basic_compositor_video']}, 03:38:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:38:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['a11yr', 03:38:06 INFO - 'ts_paint', 03:38:06 INFO - 'tpaint', 03:38:06 INFO - 'sessionrestore', 03:38:06 INFO - 'sessionrestore_no_auto_restore', 03:38:06 INFO - 'tabpaint']}, 03:38:06 INFO - 'other-e10s': {'tests': ['a11yr', 03:38:06 INFO - 'ts_paint', 03:38:06 INFO - 'tpaint', 03:38:06 INFO - 'sessionrestore', 03:38:06 INFO - 'sessionrestore_no_auto_restore', 03:38:06 INFO - 'tabpaint']}, 03:38:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:38:06 INFO - 'ts_paint', 03:38:06 INFO - 'tpaint', 03:38:06 INFO - 'sessionrestore', 03:38:06 INFO - 'sessionrestore_no_auto_restore', 03:38:06 INFO - 'tabpaint']}, 03:38:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:38:06 INFO - 'ts_paint', 03:38:06 INFO - 'tpaint', 03:38:06 INFO - 'sessionrestore', 03:38:06 INFO - 'sessionrestore_no_auto_restore', 03:38:06 INFO - 'tabpaint']}, 03:38:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['a11yr', 03:38:06 INFO - 'ts_paint', 03:38:06 INFO - 'tpaint', 03:38:06 INFO - 'sessionrestore', 03:38:06 INFO - 'sessionrestore_no_auto_restore', 03:38:06 INFO - 'tabpaint']}, 03:38:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['a11yr', 03:38:06 INFO - 'ts_paint', 03:38:06 INFO - 'tpaint', 03:38:06 INFO - 'sessionrestore', 03:38:06 INFO - 'sessionrestore_no_auto_restore', 03:38:06 INFO - 'tabpaint']}, 03:38:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['tsvgx', 03:38:06 INFO - 'tsvgr_opacity', 03:38:06 INFO - 'tart', 03:38:06 INFO - 'tscrollx', 03:38:06 INFO - 'cart']}, 03:38:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:38:06 INFO - 'tsvgr_opacity', 03:38:06 INFO - 'tart', 03:38:06 INFO - 'tscrollx', 03:38:06 INFO - 'cart']}, 03:38:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'talos_options': ['--disable-e10s'], 03:38:06 INFO - 'tests': ['tp5o']}, 03:38:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'tests': ['tp5o']}, 03:38:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'talos_options': ['--disable-e10s', 03:38:06 INFO - '--xperf_path', 03:38:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:38:06 INFO - 'tests': ['tp5n']}, 03:38:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:06 INFO - 'talos_options': ['--xperf_path', 03:38:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:38:06 INFO - 'tests': ['tp5n']}}, 03:38:06 INFO - 'talos.zip': {'path': '', 03:38:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:38:06 INFO - Running post-action listener: _resource_record_post_action 03:38:06 INFO - [mozharness: 2016-08-08 10:38:06.250931Z] Finished populate-webroot step (success) 03:38:06 INFO - [mozharness: 2016-08-08 10:38:06.251019Z] Running create-virtualenv step. 03:38:06 INFO - Running pre-action listener: _resource_record_pre_action 03:38:06 INFO - Running main action method: create_virtualenv 03:38:06 INFO - Creating virtualenv /builds/slave/test/build/venv 03:38:06 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:38:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:38:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:38:06 INFO - Using real prefix '/usr' 03:38:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:38:06 INFO - Installing distribute.............................................................................................................................................................................................done. 03:38:08 INFO - Installing pip.................done. 03:38:08 INFO - Return code: 0 03:38:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:08 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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:08 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:38:08 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:38:08 INFO - Using env: {'DISPLAY': ':0', 03:38:08 INFO - 'HOME': '/home/cltbld', 03:38:08 INFO - 'LANG': 'en_US.UTF-8', 03:38:08 INFO - 'LANGUAGE': 'en_US:en', 03:38:08 INFO - 'LOGNAME': 'cltbld', 03:38:08 INFO - 'MAIL': '/var/mail/cltbld', 03:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:08 INFO - 'MOZ_NO_REMOTE': '1', 03:38:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:08 INFO - 'NO_EM_RESTART': '1', 03:38:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:08 INFO - 'PWD': '/builds/slave/test', 03:38:08 INFO - 'SHELL': '/bin/bash', 03:38:08 INFO - 'SHLVL': '1', 03:38:08 INFO - 'TERM': 'linux', 03:38:08 INFO - 'TMOUT': '86400', 03:38:08 INFO - 'USER': 'cltbld', 03:38:08 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:08 INFO - '_': '/tools/buildbot/bin/python'} 03:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:09 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:38:09 INFO - Cleaning up... 03:38:09 INFO - Return code: 0 03:38:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:38:09 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:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:09 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:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:09 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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:09 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:38:09 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:38:09 INFO - Using env: {'DISPLAY': ':0', 03:38:09 INFO - 'HOME': '/home/cltbld', 03:38:09 INFO - 'LANG': 'en_US.UTF-8', 03:38:09 INFO - 'LANGUAGE': 'en_US:en', 03:38:09 INFO - 'LOGNAME': 'cltbld', 03:38:09 INFO - 'MAIL': '/var/mail/cltbld', 03:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:09 INFO - 'MOZ_NO_REMOTE': '1', 03:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:09 INFO - 'NO_EM_RESTART': '1', 03:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:09 INFO - 'PWD': '/builds/slave/test', 03:38:09 INFO - 'SHELL': '/bin/bash', 03:38:09 INFO - 'SHLVL': '1', 03:38:09 INFO - 'TERM': 'linux', 03:38:09 INFO - 'TMOUT': '86400', 03:38:09 INFO - 'USER': 'cltbld', 03:38:09 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:09 INFO - '_': '/tools/buildbot/bin/python'} 03:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:09 INFO - Downloading/unpacking psutil>=3.1.1 03:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:38:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:38:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:38:11 INFO - Installing collected packages: psutil 03:38:11 INFO - Running setup.py install for psutil 03:38:11 INFO - building 'psutil._psutil_linux' extension 03:38:11 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:38:11 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:38:11 INFO - building 'psutil._psutil_posix' extension 03:38:11 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:38:11 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:38:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:38:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:38:11 INFO - Successfully installed psutil 03:38:11 INFO - Cleaning up... 03:38:11 INFO - Return code: 0 03:38:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:38:11 INFO - Using env: {'DISPLAY': ':0', 03:38:11 INFO - 'HOME': '/home/cltbld', 03:38:11 INFO - 'LANG': 'en_US.UTF-8', 03:38:11 INFO - 'LANGUAGE': 'en_US:en', 03:38:11 INFO - 'LOGNAME': 'cltbld', 03:38:11 INFO - 'MAIL': '/var/mail/cltbld', 03:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:11 INFO - 'MOZ_NO_REMOTE': '1', 03:38:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:11 INFO - 'NO_EM_RESTART': '1', 03:38:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:11 INFO - 'PWD': '/builds/slave/test', 03:38:11 INFO - 'SHELL': '/bin/bash', 03:38:11 INFO - 'SHLVL': '1', 03:38:11 INFO - 'TERM': 'linux', 03:38:11 INFO - 'TMOUT': '86400', 03:38:11 INFO - 'USER': 'cltbld', 03:38:11 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:11 INFO - '_': '/tools/buildbot/bin/python'} 03:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:38:12 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:38:12 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:38:12 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:38:12 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:38:12 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:38:12 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:38:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:38:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:38:13 INFO - Installing collected packages: mozsystemmonitor 03:38:13 INFO - Running setup.py install for mozsystemmonitor 03:38:14 INFO - Successfully installed mozsystemmonitor 03:38:14 INFO - Cleaning up... 03:38:14 INFO - Return code: 0 03:38:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:38:14 INFO - Using env: {'DISPLAY': ':0', 03:38:14 INFO - 'HOME': '/home/cltbld', 03:38:14 INFO - 'LANG': 'en_US.UTF-8', 03:38:14 INFO - 'LANGUAGE': 'en_US:en', 03:38:14 INFO - 'LOGNAME': 'cltbld', 03:38:14 INFO - 'MAIL': '/var/mail/cltbld', 03:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:14 INFO - 'MOZ_NO_REMOTE': '1', 03:38:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:14 INFO - 'NO_EM_RESTART': '1', 03:38:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:14 INFO - 'PWD': '/builds/slave/test', 03:38:14 INFO - 'SHELL': '/bin/bash', 03:38:14 INFO - 'SHLVL': '1', 03:38:14 INFO - 'TERM': 'linux', 03:38:14 INFO - 'TMOUT': '86400', 03:38:14 INFO - 'USER': 'cltbld', 03:38:14 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:14 INFO - '_': '/tools/buildbot/bin/python'} 03:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:14 INFO - Downloading/unpacking jsonschema==2.5.1 03:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:38:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:38: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:38: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:38: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:38: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:38: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:38: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:38:16 INFO - Downloading functools32-3.2.3-2.tar.gz 03:38:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:38:16 INFO - warning: no files found matching '*.txt' 03:38:16 INFO - no previously-included directories found matching 'build' 03:38:16 INFO - no previously-included directories found matching 'dist' 03:38:16 INFO - no previously-included directories found matching '.git*' 03:38:16 INFO - Installing collected packages: jsonschema, functools32 03:38:16 INFO - Running setup.py install for functools32 03:38:16 INFO - warning: no files found matching '*.txt' 03:38:16 INFO - no previously-included directories found matching 'build' 03:38:16 INFO - no previously-included directories found matching 'dist' 03:38:16 INFO - no previously-included directories found matching '.git*' 03:38:16 INFO - Successfully installed jsonschema functools32 03:38:16 INFO - Cleaning up... 03:38:16 INFO - Return code: 0 03:38:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:38: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:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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', '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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:38: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 functools32==3.2.3-2 03:38:16 INFO - Using env: {'DISPLAY': ':0', 03:38:16 INFO - 'HOME': '/home/cltbld', 03:38:16 INFO - 'LANG': 'en_US.UTF-8', 03:38:16 INFO - 'LANGUAGE': 'en_US:en', 03:38:16 INFO - 'LOGNAME': 'cltbld', 03:38:16 INFO - 'MAIL': '/var/mail/cltbld', 03:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:16 INFO - 'MOZ_NO_REMOTE': '1', 03:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:16 INFO - 'NO_EM_RESTART': '1', 03:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:16 INFO - 'PWD': '/builds/slave/test', 03:38:16 INFO - 'SHELL': '/bin/bash', 03:38:16 INFO - 'SHLVL': '1', 03:38:16 INFO - 'TERM': 'linux', 03:38:16 INFO - 'TMOUT': '86400', 03:38:16 INFO - 'USER': 'cltbld', 03:38:16 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:16 INFO - '_': '/tools/buildbot/bin/python'} 03:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:38:16 INFO - Cleaning up... 03:38:16 INFO - Return code: 0 03:38:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:38: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:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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', '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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:38: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 blobuploader==1.2.4 03:38:16 INFO - Using env: {'DISPLAY': ':0', 03:38:16 INFO - 'HOME': '/home/cltbld', 03:38:16 INFO - 'LANG': 'en_US.UTF-8', 03:38:16 INFO - 'LANGUAGE': 'en_US:en', 03:38:16 INFO - 'LOGNAME': 'cltbld', 03:38:16 INFO - 'MAIL': '/var/mail/cltbld', 03:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:16 INFO - 'MOZ_NO_REMOTE': '1', 03:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:16 INFO - 'NO_EM_RESTART': '1', 03:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:16 INFO - 'PWD': '/builds/slave/test', 03:38:16 INFO - 'SHELL': '/bin/bash', 03:38:16 INFO - 'SHLVL': '1', 03:38:16 INFO - 'TERM': 'linux', 03:38:16 INFO - 'TMOUT': '86400', 03:38:16 INFO - 'USER': 'cltbld', 03:38:16 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:16 INFO - '_': '/tools/buildbot/bin/python'} 03:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:17 INFO - Downloading/unpacking blobuploader==1.2.4 03:38:17 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:38:17 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:38:17 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:38:17 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:38:17 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:38:17 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:38:18 INFO - Downloading blobuploader-1.2.4.tar.gz 03:38:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:38:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:38: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:38: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:38: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:38: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:38: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:38: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:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:38:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:38: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:38: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:38: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:38: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:38: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:38: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:38:19 INFO - Downloading docopt-0.6.1.tar.gz 03:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:38:19 INFO - Installing collected packages: blobuploader, requests, docopt 03:38:19 INFO - Running setup.py install for blobuploader 03:38:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:38:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:38:19 INFO - Running setup.py install for requests 03:38:20 INFO - Running setup.py install for docopt 03:38:20 INFO - Successfully installed blobuploader requests docopt 03:38:20 INFO - Cleaning up... 03:38:20 INFO - Return code: 0 03:38:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:20 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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:20 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:38:20 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:38:20 INFO - Using env: {'DISPLAY': ':0', 03:38:20 INFO - 'HOME': '/home/cltbld', 03:38:20 INFO - 'LANG': 'en_US.UTF-8', 03:38:20 INFO - 'LANGUAGE': 'en_US:en', 03:38:20 INFO - 'LOGNAME': 'cltbld', 03:38:20 INFO - 'MAIL': '/var/mail/cltbld', 03:38:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:20 INFO - 'MOZ_NO_REMOTE': '1', 03:38:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:20 INFO - 'NO_EM_RESTART': '1', 03:38:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:20 INFO - 'PWD': '/builds/slave/test', 03:38:20 INFO - 'SHELL': '/bin/bash', 03:38:20 INFO - 'SHLVL': '1', 03:38:20 INFO - 'TERM': 'linux', 03:38:20 INFO - 'TMOUT': '86400', 03:38:20 INFO - 'USER': 'cltbld', 03:38:20 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:20 INFO - '_': '/tools/buildbot/bin/python'} 03:38:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:20 INFO - Running setup.py (path:/tmp/pip-c905Zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:20 INFO - Running setup.py (path:/tmp/pip-AaU1uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:20 INFO - Running setup.py (path:/tmp/pip-knuA5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:20 INFO - Running setup.py (path:/tmp/pip-o6u1Qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:20 INFO - Running setup.py (path:/tmp/pip-bbF5ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:20 INFO - Running setup.py (path:/tmp/pip-vJWpm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:21 INFO - Running setup.py (path:/tmp/pip-JbfOmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:21 INFO - Running setup.py (path:/tmp/pip-mScJh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:21 INFO - Running setup.py (path:/tmp/pip-OP5cK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:21 INFO - Running setup.py (path:/tmp/pip-eoFFoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:21 INFO - Running setup.py (path:/tmp/pip-QNH8AV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:21 INFO - Running setup.py (path:/tmp/pip-c8XcnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:21 INFO - Running setup.py (path:/tmp/pip-Y9RJg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:21 INFO - Running setup.py (path:/tmp/pip-D2qPGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:21 INFO - Running setup.py (path:/tmp/pip-hKxfl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:21 INFO - Running setup.py (path:/tmp/pip-Un_j0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:21 INFO - Running setup.py (path:/tmp/pip-e7chxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:38:21 INFO - Running setup.py install for manifestparser 03:38:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:38:21 INFO - Running setup.py install for mozcrash 03:38:22 INFO - Running setup.py install for mozdebug 03:38:22 INFO - Running setup.py install for mozdevice 03:38:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Running setup.py install for mozfile 03:38:22 INFO - Running setup.py install for mozhttpd 03:38:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Running setup.py install for mozinfo 03:38:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Running setup.py install for mozInstall 03:38:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Running setup.py install for mozleak 03:38:22 INFO - Running setup.py install for mozlog 03:38:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Running setup.py install for moznetwork 03:38:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:38:22 INFO - Running setup.py install for mozprocess 03:38:23 INFO - Running setup.py install for mozprofile 03:38:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:38:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:38:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:38:23 INFO - Running setup.py install for mozrunner 03:38:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:38:23 INFO - Running setup.py install for mozscreenshot 03:38:23 INFO - Running setup.py install for moztest 03:38:23 INFO - Running setup.py install for mozversion 03:38:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:38:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:38:23 INFO - Cleaning up... 03:38:23 INFO - Return code: 0 03:38:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:23 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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:23 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:38:23 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:38:23 INFO - Using env: {'DISPLAY': ':0', 03:38:23 INFO - 'HOME': '/home/cltbld', 03:38:23 INFO - 'LANG': 'en_US.UTF-8', 03:38:23 INFO - 'LANGUAGE': 'en_US:en', 03:38:23 INFO - 'LOGNAME': 'cltbld', 03:38:23 INFO - 'MAIL': '/var/mail/cltbld', 03:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:23 INFO - 'MOZ_NO_REMOTE': '1', 03:38:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:23 INFO - 'NO_EM_RESTART': '1', 03:38:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:23 INFO - 'PWD': '/builds/slave/test', 03:38:23 INFO - 'SHELL': '/bin/bash', 03:38:23 INFO - 'SHLVL': '1', 03:38:23 INFO - 'TERM': 'linux', 03:38:23 INFO - 'TMOUT': '86400', 03:38:23 INFO - 'USER': 'cltbld', 03:38:23 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:23 INFO - '_': '/tools/buildbot/bin/python'} 03:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:23 INFO - Running setup.py (path:/tmp/pip-JAp6z1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:23 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:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:23 INFO - Running setup.py (path:/tmp/pip-oy8wZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:23 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:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:23 INFO - Running setup.py (path:/tmp/pip-IJMBSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:24 INFO - Running setup.py (path:/tmp/pip-SFtdOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:24 INFO - Running setup.py (path:/tmp/pip-m2Imur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:24 INFO - Running setup.py (path:/tmp/pip-FZU2V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:24 INFO - Running setup.py (path:/tmp/pip-WttCAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:24 INFO - Running setup.py (path:/tmp/pip-xaDSo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:24 INFO - Running setup.py (path:/tmp/pip-CYtVCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:24 INFO - Running setup.py (path:/tmp/pip-3kl23Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:24 INFO - Running setup.py (path:/tmp/pip-qBcFAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:24 INFO - Running setup.py (path:/tmp/pip-nQVd9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:24 INFO - Running setup.py (path:/tmp/pip-Bz2rff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:24 INFO - Running setup.py (path:/tmp/pip-KWJ5SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:24 INFO - Running setup.py (path:/tmp/pip-PLNodb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:24 INFO - Running setup.py (path:/tmp/pip-zukNzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:24 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:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:24 INFO - Running setup.py (path:/tmp/pip-KtLuu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:25 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:38:25 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:38:25 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:38:25 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:38:25 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:38:25 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:38:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:38: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:38: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:38: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:38: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:38: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:38: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:38:26 INFO - Downloading blessings-1.6.tar.gz 03:38:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:38:27 INFO - Installing collected packages: blessings 03:38:27 INFO - Running setup.py install for blessings 03:38:27 INFO - Successfully installed blessings 03:38:27 INFO - Cleaning up... 03:38:27 INFO - Return code: 0 03:38:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:38:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:27 INFO - Reading from file tmpfile_stdout 03:38:27 INFO - Current package versions: 03:38:27 INFO - argparse == 1.2.1 03:38:27 INFO - blessings == 1.6 03:38:27 INFO - blobuploader == 1.2.4 03:38:27 INFO - docopt == 0.6.1 03:38:27 INFO - functools32 == 3.2.3-2 03:38:27 INFO - jsonschema == 2.5.1 03:38:27 INFO - manifestparser == 1.1 03:38:27 INFO - mozInstall == 1.12 03:38:27 INFO - mozcrash == 0.17 03:38:27 INFO - mozdebug == 0.1 03:38:27 INFO - mozdevice == 0.48 03:38:27 INFO - mozfile == 1.2 03:38:27 INFO - mozhttpd == 0.7 03:38:27 INFO - mozinfo == 0.9 03:38:27 INFO - mozleak == 0.1 03:38:27 INFO - mozlog == 3.3 03:38:27 INFO - moznetwork == 0.27 03:38:27 INFO - mozprocess == 0.23 03:38:27 INFO - mozprofile == 0.28 03:38:27 INFO - mozrunner == 6.12 03:38:27 INFO - mozscreenshot == 0.1 03:38:27 INFO - mozsystemmonitor == 0.3 03:38:27 INFO - moztest == 0.7 03:38:27 INFO - mozversion == 1.4 03:38:27 INFO - psutil == 3.1.1 03:38:27 INFO - requests == 1.2.3 03:38:27 INFO - wsgiref == 0.1.2 03:38:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:27 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:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:27 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:38:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:27 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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:27 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:38:27 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:38:27 INFO - Using env: {'DISPLAY': ':0', 03:38:27 INFO - 'HOME': '/home/cltbld', 03:38:27 INFO - 'LANG': 'en_US.UTF-8', 03:38:27 INFO - 'LANGUAGE': 'en_US:en', 03:38:27 INFO - 'LOGNAME': 'cltbld', 03:38:27 INFO - 'MAIL': '/var/mail/cltbld', 03:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:27 INFO - 'MOZ_NO_REMOTE': '1', 03:38:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:27 INFO - 'NO_EM_RESTART': '1', 03:38:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:27 INFO - 'PWD': '/builds/slave/test', 03:38:27 INFO - 'SHELL': '/bin/bash', 03:38:27 INFO - 'SHLVL': '1', 03:38:27 INFO - 'TERM': 'linux', 03:38:27 INFO - 'TMOUT': '86400', 03:38:27 INFO - 'USER': 'cltbld', 03:38:27 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:27 INFO - '_': '/tools/buildbot/bin/python'} 03:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 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:38:27 INFO - Cleaning up... 03:38:27 INFO - Return code: 0 03:38:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:38:27 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:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:27 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:38:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:27 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 0x1c54030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f15180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', '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:38:27 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:38:27 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:38:27 INFO - Using env: {'DISPLAY': ':0', 03:38:27 INFO - 'HOME': '/home/cltbld', 03:38:27 INFO - 'LANG': 'en_US.UTF-8', 03:38:27 INFO - 'LANGUAGE': 'en_US:en', 03:38:27 INFO - 'LOGNAME': 'cltbld', 03:38:27 INFO - 'MAIL': '/var/mail/cltbld', 03:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:27 INFO - 'MOZ_NO_REMOTE': '1', 03:38:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:27 INFO - 'NO_EM_RESTART': '1', 03:38:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:27 INFO - 'PWD': '/builds/slave/test', 03:38:27 INFO - 'SHELL': '/bin/bash', 03:38:27 INFO - 'SHLVL': '1', 03:38:27 INFO - 'TERM': 'linux', 03:38:27 INFO - 'TMOUT': '86400', 03:38:27 INFO - 'USER': 'cltbld', 03:38:27 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:27 INFO - '_': '/tools/buildbot/bin/python'} 03:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:38:27 INFO - Cleaning up... 03:38:27 INFO - Return code: 0 03:38:27 INFO - Running post-action listener: _resource_record_post_action 03:38:27 INFO - Running post-action listener: _start_resource_monitoring 03:38:27 INFO - Starting resource monitoring. 03:38:27 INFO - [mozharness: 2016-08-08 10:38:27.905913Z] Finished create-virtualenv step (success) 03:38:27 INFO - [mozharness: 2016-08-08 10:38:27.906348Z] Running install step. 03:38:27 INFO - Running pre-action listener: _resource_record_pre_action 03:38:27 INFO - Running main action method: install 03:38:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:28 INFO - Reading from file tmpfile_stdout 03:38:28 INFO - Detecting whether we're running mozinstall >=1.0... 03:38:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:38:28 INFO - Reading from file tmpfile_stdout 03:38:28 INFO - Output received: 03:38:28 INFO - Usage: mozinstall [options] installer 03:38:28 INFO - Options: 03:38:28 INFO - -h, --help show this help message and exit 03:38:28 INFO - -d DEST, --destination=DEST 03:38:28 INFO - Directory to install application into. [default: 03:38:28 INFO - "/builds/slave/test"] 03:38:28 INFO - --app=APP Application being installed. [default: firefox] 03:38:28 INFO - mkdir: /builds/slave/test/build/application 03:38:28 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:38:28 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:38:44 INFO - Reading from file tmpfile_stdout 03:38:44 INFO - Output received: 03:38:44 INFO - /builds/slave/test/build/application/firefox/firefox 03:38:44 INFO - Running post-action listener: _resource_record_post_action 03:38:44 INFO - [mozharness: 2016-08-08 10:38:44.884051Z] Finished install step (success) 03:38:44 INFO - [mozharness: 2016-08-08 10:38:44.884391Z] Running run-tests step. 03:38:44 INFO - Running pre-action listener: _resource_record_pre_action 03:38:44 INFO - Running main action method: run_tests 03:38:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:38:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:38:44 INFO - Python 2.7.3 03:38:44 INFO - Return code: 0 03:38:44 INFO - grabbing minidump binary from tooltool 03:38:44 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:38:44 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 0x1f133b0>, '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 0x1f142b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f14740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:38:44 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:38:44 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:38:44 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:38:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:38:44 INFO - Return code: 0 03:38:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:38:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:38:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:38:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:38:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:38:44 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/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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:38:44 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/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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:38:44 INFO - Using env: {'DISPLAY': ':0', 03:38:44 INFO - 'HOME': '/home/cltbld', 03:38:44 INFO - 'LANG': 'en_US.UTF-8', 03:38:44 INFO - 'LANGUAGE': 'en_US:en', 03:38:44 INFO - 'LOGNAME': 'cltbld', 03:38:44 INFO - 'MAIL': '/var/mail/cltbld', 03:38:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:44 INFO - 'MOZ_NO_REMOTE': '1', 03:38:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:44 INFO - 'NO_EM_RESTART': '1', 03:38:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:44 INFO - 'PWD': '/builds/slave/test', 03:38:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:44 INFO - 'SHELL': '/bin/bash', 03:38:44 INFO - 'SHLVL': '1', 03:38:44 INFO - 'TERM': 'linux', 03:38:44 INFO - 'TMOUT': '86400', 03:38:44 INFO - 'USER': 'cltbld', 03:38:44 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413', 03:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:44 INFO - '_': '/tools/buildbot/bin/python'} 03:38:44 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/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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:38:45 INFO - mozversion application_buildid: 20160808004011 03:38:45 INFO - mozversion application_changeset: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 03:38:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:38:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:38:45 INFO - mozversion application_name: Firefox 03:38:45 INFO - mozversion application_remotingname: firefox-dev 03:38:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:38:45 INFO - mozversion application_vendor: Mozilla 03:38:45 INFO - mozversion application_version: 50.0a2 03:38:45 INFO - mozversion platform_buildid: 20160808004011 03:38:45 INFO - mozversion platform_changeset: 93a54e1c76c1602c65d2ba7bd1b6f20a997765e5 03:38:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:38:45 INFO - mozversion platform_version: 50.0a2 03:38:45 INFO - using testdate: 1470652725 03:38:45 INFO - actual date: 1470652725 03:38:45 INFO - starting webserver on 'localhost:47975' 03:38:45 INFO - SUITE-START | Running 2 tests 03:38:45 INFO - TEST-START | dromaeo_css 03:38:45 INFO - operating with platform_type : linux_ 03:38:45 INFO - Initialising browser for dromaeo_css test... 03:38:45 INFO - TEST-INFO | started process 18680 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwOTBnM/profile http://localhost:47975/getInfo.html) 03:38:49 INFO - PROCESS | 18680 | __metrics Screen width/height:1600/1200 03:38:49 INFO - PROCESS | 18680 | colorDepth:24 03:38:49 INFO - PROCESS | 18680 | Browser inner width/height: 1024/697 03:38:49 INFO - PROCESS | 18680 | __metrics 03:38:50 INFO - PROCESS | 18680 | 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:38:50 INFO - PROCESS | 18680 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:38:51 INFO - TEST-INFO | 18680: exit 0 03:38:51 INFO - Browser initialized. 03:38:51 INFO - Running cycle 1/1 for dromaeo_css test... 03:38:51 INFO - TEST-INFO | started process 18766 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwOTBnM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:39:03 INFO - PROCESS | 18766 | RSS: Main: 145170432 03:39:03 INFO - PROCESS | 18766 | 03:39:54 INFO - PROCESS | 18766 | 1470652794064 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:42:03 INFO - PROCESS | 18766 | Cycle 1(1): loaded http://localhost:47975/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47975/tests/dromaeo/cssquery-ext.html) 03:42:04 INFO - PROCESS | 18766 | RSS: Main: 220909568 03:42:04 INFO - PROCESS | 18766 | 03:45:06 INFO - PROCESS | 18766 | Cycle 1(1): loaded http://localhost:47975/tests/dromaeo/cssquery-ext.html (next: http://localhost:47975/tests/dromaeo/cssquery-jquery.html) 03:45:07 INFO - PROCESS | 18766 | RSS: Main: 237256704 03:45:07 INFO - PROCESS | 18766 | 03:48:09 INFO - PROCESS | 18766 | Cycle 1(1): loaded http://localhost:47975/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47975/tests/dromaeo/cssquery-mootools.html) 03:48:09 INFO - PROCESS | 18766 | RSS: Main: 206393344 03:48:09 INFO - PROCESS | 18766 | 03:51:11 INFO - PROCESS | 18766 | Cycle 1(1): loaded http://localhost:47975/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47975/tests/dromaeo/cssquery-prototype.html) 03:51:12 INFO - PROCESS | 18766 | RSS: Main: 175239168 03:51:12 INFO - PROCESS | 18766 | 03:54:14 INFO - PROCESS | 18766 | Cycle 1(1): loaded http://localhost:47975/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47975/tests/dromaeo/cssquery-yui.html) 03:54:15 INFO - PROCESS | 18766 | RSS: Main: 178081792 03:54:15 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | Cycle 1(1): loaded http://localhost:47975/tests/dromaeo/cssquery-yui.html (next: http://localhost:47975/tests/dromaeo/cssquery-dojo.html) 03:57:18 INFO - PROCESS | 18766 | RSS: Main: 267390976 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | __start_tp_report 03:57:18 INFO - PROCESS | 18766 | _x_x_mozilla_page_load 03:57:18 INFO - PROCESS | 18766 | _x_x_mozilla_page_load_details 03:57:18 INFO - PROCESS | 18766 | |i|pagename|runs| 03:57:18 INFO - PROCESS | 18766 | |0;dojo.html;3241.546183534305;3269.4605390110646;3274.5251938468914;3291.637919828817;3317.3365326934604;5696.829095127147;5792.899349123663;5793.71031448428;5817.912731309034;5841.269841269841;5693.3452652944925;5738.885222295552;5743.253377060976;5752.7986520471795;5760.827175184741;5157.5358217760395;5196.272521846944;5214.66104703195;5218.269442278084;5274.156135018397;5276.657017293864;5292.417834038252;5311.9468805312035;5324.440933701963;5329.25390445338;5207.192885102796;5226.895462090756;5261.1319132343115;5264.0261551613075;5327.786888524454;5329.893402131955;5333.2000199969925;5342.866428339303;5349.117395629716;5354.41101478837;5070.29015937769;5090.847274581768;5099.056674515189;5224.660397074216;5241.3710354757295;5610.046292130348;5677.716114194315;5679.290088738908;5693.231413759131;5715.31416230055;6119.326874043852;6138.202033735631;6143.692815359259;6143.723532441051;6143.846403839895;5980.28236611605;6066.908996365058;6112.572119951606;6132.845047373404;6180.567360284782;5772;5781.046127388976;5793.797217097382;5794.565407594448;5797.623154495029;6316.368363163647;6321.273053598895;6328.145700330397;6329.746810127635;6334.3032266450655;5851.502622277154;6006.579539432198;6077.24034495688;6079.726412311456;6108.731828471747;6198.2252218472695;6211.409916057967;6211.9689401553605;6218.81343559694;6220.813375598739;6123.84690382744;6173.47525460332;6193.349698281616;6194.163787888578;6196.132541444202;5957.702114894238;6054.212952316171;6123.8162855114415;6131.172291740647;6148.907766383508;4697.600703940203;4733.5266473352385;4737.857864264078;4753.762311884392;4764.666473346832;4204.306289462265;4216.746995180299;4218.514870789837;4227.429297044921;4237.300845360481;5697.173909783059;5719.885602287931;5761.510271626875;5766.798162064308;5794.710264486759;6106.084087386927;6110.572259941761;6111.113888486145;6118.694065296718;6132.6627035513475;5781.132830075522;5784.623999440023;5785.566082543826;5791.5656325775735;5792.88414231713;5103.821366252163;5104.310918026019;5166.535011848951;5174.818881339135;5196.350456192976;6217.378262173746;6226.813195604139;6233.252009758858;6248.625082495156;6249.0626406039455;5948.196993405835;6066.666333351708;6067.939320606914;6069.089636554552;6071.9981203101;6158.538109641705;6294.71673774672;6348.396902294459;6362.491000720024;6429.810485060279;6128.9693551531955;6132.47873930703;6132.662703551169;6142.938570614237;6145.047517634774;6163.322034576104;6213.502919766317;6216.502679785516;6216.720247588779;6218.067289906369;5843.29880414336;5969.582129250911;6030.6080104793045;6038.788642397495;6095.268567771718;6014.278286605635;6052.606580572248;6054.0918862169265;6055.212822333068;6062.605930614496;5953.493953014043;5987;5987.221661184019;5997.490213331741;6012.188354572077;5926.051831706906;5928.3775203603;5994.250718660168;5995.430434108753;6051.304100028553;5818.592698511064;5822.039363504974;5831.679257640871;5835.212246346859;5836.270466191726 03:57:18 INFO - PROCESS | 18766 | |1;ext.html;622.37140488107;627.4447114303841;663.8771827211966;665.7569986954763;694.4964900447173;34845;45790.54209457905;47414.76292618541;47489.525104748944;47548.52451475484;48006.51993480064;48417.03165936679;48482;48533.272000919926;48545.757271213595;8486.448380855256;9225.400348977311;9383.296252781034;9388.202002829768;11382.601608943689;11153.776924461506;11275.492602832852;11279.49242284095;11284.548618055267;11286;11327.660170194908;11364.715882102932;11367.488463019143;11371.147163962694;11384.089272858193;11300.660980170567;11334.263272887236;11360.715982100432;11362;11366.090712743002;11093.889061109367;11117.055050320732;11177.66467005991;11194.10447164225;11195.888041119566;44997.550024499666;45270;45487.77256113698;45543.316850247276;45559.31661025087;3835.9808200959096;3853.4797802296894;3858.9228215435533;4033.7983100845136;4065.8983525411804;34905;35184.64815351839;35324;35387.29225415503;36164.276714465566;4289.742615443052;4290.904037797262;4342.609165175118;4361.563843615645;4387.583179597933;385641;546436;553480;556448;556896;60997;61283.387166127766;61451.385486144565;61462;61471.69264153651;39332;40284;40315.395269070985;68644.6567767168;69020;19473.415797526097;19720.30978915731;19744.802551974582;19753.40739777809;19756.70364944527;13168.934155329354;14907.478238261609;15074.698506029818;15155.772663410058;15206.695866082617;20372.981350932394;20557.177712891316;20593.38219853407;20681.68977465339;20697.793022069585;7172.282771722786;7236;7405.927139745465;7433.739819106413;7448.519787784554;1006.8691070160978;1082.4479515447022;1118.6644006797928;1164.1501703756305;1221.6179739599957;35915.820420897726;36081.09797255037;36347.27305453876;36399.63600364015;36447.27105457876;1129.4465711801158;1154.503563467717;1158.6060739348661;1177.4524845946676;1226.2274766896976;16483.91758041226;17751.644967100587;17992.730109048374;18110.547236318933;18135;582730;582838;588725;589817;590265;62775.37224627787;63462;63509.68245158745;63539.6823015882;63588.68205658942;1362.768329384007;1410.5274732964367;1417.4826188441145;1515.9393624255126;1517.87856971442;30487.237819054702;37933;38643.80678096704;38677;38836.22327553377;61111.69444152751;61895.38104618896;61969.38030619817;62079.37920620736;62135.6893215531;500730;509161;510652;510927;511542;30697.846510767304;30862;31512.842435788592;31850;33258.83370583132;53437.46562534325;53565.46434535761;54052.45947540474;54330.18504722353;55305;44581.77709111434;44819.55180448154;45083.77458112688;45852;46631.76684116694;44311;46333.53666463292;46752.06495869996;47041.52958470371;47144.7642761784;44628.33057504075;44690.776546117064;44706.77646611746;44728.316301022045;45115.323270150315;38860;40689.796551017054;40918.1816363677;40961;41448.58551414447;30644.233894152825;30874.53688194634;30929.845350773103;31042.379152417278;31194 03:57:18 INFO - PROCESS | 18766 | |2;jquery.html;378.38512417321834;404.28643444320795;430.24492016511016;434.6023388599432;437.35926867139636;15148.394064237431;20200.494987625316;20708.585828283427;20762.896185519072;21365.252216172437;20019.499512512193;20023.299184528543;20085.59828803423;20273.59452810943;20448.386548403534;5848.590598658096;5905.350411454737;5984.251968503937;6025.668588227646;6037.516998640121;5907.527397808187;5961.046232602785;6046.2744470663365;6047.425494578008;6048.667323297217;5904.734286957076;5919.1713160157615;5933.614315069528;5955.106733989893;5960.016597261447;5744.224529688522;5898.911516327258;5987.760489580412;5993.250843644544;5994.010988186966;5343.572514198893;5363.302770639831;5373.408925018284;5375.623706340558;5376.973115134399;24397.634035489482;24441.26676199717;24507.632385514233;24728.876355618107;24758.504829903482;2492.1526680928573;2515.8364706294033;2584.160147951923;2660.734104962733;3240.570674026799;14681.339339729739;15005.24973751308;15180;15283.159426231552;15486.225688715633;1990.9004549772599;2061.4228016155353;2076.8390747542876;2082.739657542807;2100.842436817245;82449;83255;84683;85848.57075714644;87167.56416217878;11135.554577816796;11174.832377514344;11302.773944521063;11366.715832104264;11444.713882153013;15270.541883743506;15357.616059598377;15381.846181538041;15382.077075375513;15398.153101579297;8389.916100839035;8528.403011789114;8821.161989610977;8838.602262898185;8959.686410975584;8506.149385061568;8554;8617.8813925193;8800.691975780817;8808;7106.2183159852375;7140;7167.569945803269;7170.6056166910275;7183.640817959081;2043.2746375036647;2044.9284275050302;2049.282751037125;2060.8763474191596;2061;990.9900900990898;1022.1311884897926;1056.265895202842;1084.5715942202785;1091.9332564459262;20140.19439222444;20832.58334833325;21199.68200476994;21458.356249312546;21494.14023439045;1023.5240613114788;1040.5785656809005;1064.8402739589123;1111.394290111891;1151.8732939376662;11292.943535282271;12190.81713774294;12210.26738395699;12214.708577939504;12286.631401057804;559097;565946;567049;569277;583093;86832;88005.55997219973;89257;89374;89385.55307223422;1036.9066783989476;1086.3373342261214;1104.0836106031975;1107.0258172807878;1108.0526150141675;18167;18550.44348669542;18784.436466906016;19203.533687890736;19344.90327548353;40916.79541602273;42385.5761442394;42438.36342454804;42955.7852210737;47286.05427891492;58752;58811;59195.11207331807;61121.38878611157;61814.69092654508;8746.081661425458;8825.11748825138;8966.730998070068;8993.010768815557;9014.909850901407;13214.405351759386;13353;13614.387352569356;14044.648883777987;14251.714965700421;11147.944260278648;11402.657920262405;11773.587924422947;11801.468933897824;12519.874801252236;12048.216865903689;12124.818127727915;12170.634880953585;12268.263904165779;12372.319522425993;12249.203801752858;12424.689382765504;12516.624501264976;12566.497340106293;12589;7919.524828510308;8151.388645851704;8302.335813134814;9267.304952128483;9363.391379560308;8938.4190027648;8939.687110951085;10869.402182879701;12068.275903445821;12670.683232918882 03:57:18 INFO - PROCESS | 18766 | |3;mootools.html;1384.8268966379203;1505.2699440771228;1563.5309407177847;1716.5536960390302;1888.8111188881114;9253.491057991807;9598;9679.080487353698;9891.851622225655;9931.354461959967;9651.986541413156;9807.607695692182;9819.509024548779;9873.950630246838;9884.308098433112;2500.812439067068;2513.8240323177383;2517.987410062947;2520.193538067819;2520.4707011527603;2422.382292515406;2521.8991240350365;2527.633493143493;2529.380301826055;2530.8861101250395;2443.780059794618;2472.221250306148;2504.0109156883022;2510.7363726808694;2511.5855883779154;2449.0938352809485;2455.4107014316523;2463.8152138589676;2497.9375515612073;2499.2252401755513;2404.230646193219;2431.963520547193;2437.183543512925;2437.731849496554;2443.9266821995193;9757.512124393823;9839.114479696862;9916.157126644244;9933.950330248375;9939.80120397588;5707.6575405475805;5842.591018628697;5848.619839710405;5851.64890106595;5857.648541087548;3554.5556805399324;3578.212793185521;3605.513255710498;3626.202235508184;3643.6538528839983;1097.5993762276712;1097.8847221041756;1103.1450625765017;1112.3826276416585;1136.8749437561862;13869.167849929037;13939.581812545639;13944.930275348559;13971.860281397157;14002.299885005708;4771.832985845548;4788.4253889533475;4837.274408838703;4935.925961110586;4950.579200767975;10714.624988125379;10732.195085368628;10735.409552474533;10760.784784304271;10771;5671.914921276184;5674.404187560247;5680.5171560417;5687.232223649837;5698.430156984268;4627.097715945426;4784.6889952153;5229.50319719626;5233.319668443154;5314.441983591744;4195.265828480065;4216.557261487561;4242.915141697149;4283.528811830696;4285.164392943346;1221.2829438090503;1228.5270170984236;1247.2391840976998;1255.5793809074062;1255.993720031394;894.5437826708426;944.5088553951906;987.698751880669;1037.865077539915;1046.1909772261351;6424.518161137933;6439.8068057958335;6631.668416579152;6634.734610615521;6823.0106634537715;498.2501335490064;499.95000499949714;510.1684951025835;512.674451723151;515.1371452816592;4426.18115648606;4446.2663660495655;4601.102784956901;4710.552497512731;4729.314249433882;12223.1443798935;12564.560240391542;12651.0511711622;12655;12660.746785064432;11458.255213411101;11927.403629818647;11948.761024779456;12574.1198116131;12588.307643079535;8966.820663586692;9055.139761722758;9109.77225569366;9170.44977301364;9181.173694367406;6412.294647588668;6521.804345869631;6552.57408268461;6581.934180658323;6662.467002639681;11075.446227688744;11342.60300889465;11360.54557817678;11511.424428778695;11578.36319002463;10495.947520262349;11133.665990020312;11645.068394528587;11677.240979336317;11806.940965295118;8186.836263274582;8422.663093476192;8465;8496.575171241537;8517;9133;9138.543072846463;9146.359754817098;9186.40288381253;9189.862152067859;7819.530828150329;8019.879701804594;8028.879566806386;8105.148959359182;8127.593620318842;7892.289693927344;7941.722039728582;8018.077921038922;8024.558649274346;8393.37049721261;7535.811604709706;7628.771136865903;7646.044244469442;7651.273129052731;7656;5441.401445841034;5524.392316845224;5539.279893613804;5593.860153496195;5650.29371328584;5770.942290577041;5808.593398462067;5828.970855145697;5840.649561026352;5879.882402352015 03:57:18 INFO - PROCESS | 18766 | |4;prototype.html;358.8582509908586;361.2449979542757;363.65089514066494;364.44422256059505;368.8266514738072;5512.834614961547;5570.3315602127705;5582.972085139579;5587.944120558793;5588.832335029955;5366.007288651602;5432.809851655193;5434.076207044802;5535.833924982257;5539;4341.1317736452675;4364.541723119074;4372.519022907478;4373.584509471595;4388.429504164462;4347.717398369112;4349.521552629209;4383.561643835623;4383.890402739925;4389.6049355558;4339;4356.607905288539;4372.234858899696;4399.604035636792;4417.5201307562;4276.5295817460055;4303.462067241595;4335.739855608658;4343.913121737579;4367.67242456817;3877.418387241936;3883.728139030269;3887.2031233597045;3891.8324502649375;3912.197999410114;5290.92063619046;5308.389535203424;5350.491703288181;5368.677879327252;5378.784848606051;4852.42296253719;4926.955313544875;4964.081644895669;4972.254161875711;4994.650374473788;5678.296511046711;5692.800751973702;5703.117971069537;5708.514776244025;5731.512821410185;4285.528591854893;4289.099289149282;4290.871273861789;4294.162638285536;4294.634956028742;5650.6892120933335;5690.2033715279895;5848.590598658096;5937.383803078788;5963.880722385571;4336.460556502459;4353.216421044179;4358.3680366347335;4368.497622773358;4399.472063352418;4437.64498840092;4447.755373454426;4451.10977804434;4451.1765323415275;4463.642908567307;4749.35883655702;4754.168020596382;4918.926216106761;4999.175136102575;5011.523905228997;5680.801171958961;5711.372258906192;5713.8285851424525;5728.684922329312;5735.56238598594;1486.2642991719167;1503.8571335723088;1505.7214415333199;1513.2585033333587;1513.43246282644;1015.0712098429854;1032.3702541449709;1044.6239353832614;1086.842407850873;1101.2346419238552;5473.151661492475;5475.206095116186;5488.533474654699;5535.418781028015;5579.330480342385;5747.712614369265;5809.331926828384;5812.6221795584;5816.563757718188;5837.503812175928;5301.734913254322;5517.282753242052;5580.441955804387;5580.748866301026;5641.323041234996;3079.6766339534024;3119.7816152869536;3124.9219019438256;3126.8905588304756;3131.6711745266875;5843.503302219274;6092.39076092396;6095.451409373178;6104.664243466652;6153.169322141453;6000.21997140369;6027.9095813562835;6035.155078289043;6037.094435834572;6041.667708276087;6111.572189946661;6112.266528016667;6144.631322120597;6148.292946311142;6170.784022559189;5608.579356548356;5852.853678657897;5888.499477544457;5912.586118971631;5922.289325280994;5841.620294680833;5862.149988251765;5872.853178670567;5877.2947246329;5898.793542226002;5590.86022849432;5864.208331875142;6058.939410605837;6095.542834287535;6164.599301045418;4153.522344930372;4183.748975061506;4202.390653355185;4209.936850947177;4226.767527786002;5906.114082887601;5960.910586341121;5961.612495187799;5984.461398474158;5991.7902873399225;5630.634008789416;5688.326486551315;5738.282714660668;5757;5841.240638716939;5683.545316374597;5689.91891540606;5721.942780572141;5728.598998070095;5759.913601296068;5527.9220551991975;5654.406287339934;5722.62802917809;5726.141078838207;5734.827955161351;5761.8559536011935;5769.278840144982;5771.431712887893;5788.189653448605;5790.594658373873;5647.407022262603;5651.8304450866535;5655.123455864347;5662.490375866192;5663.915041274302 03:57:18 INFO - PROCESS | 18766 | |5;yui.html;810.3274282345653;856.4475913036088;869.8434281829271;892.9642814287427;916.3997581584057;16689.415870444536;17708.46874593761;17894.463166105037;17920.641587168266;17943.371981980617;15787.605309867258;16078.115703636295;16209;16242.51272461828;16253.756193657104;878.1525827576387;895.807401408697;895.9552022398886;897.537768049455;898.7124120281512;736.7126820539994;865.8528050231475;891.6656253904786;891.6879092317702;895.4671970177734;917.1241464401514;918.701422037837;918.7978644698155;919.1773362840254;919.5953780336664;915.6291701860757;915.716128000322;917.2982668258787;917.7476194046691;918.2699753695828;558.483402852364;572.928383952006;661.507177153024;671.0973740319312;672.7611697847292;15715;16231.51305460838;16266.75599866003;16275.104869232187;16312.347506099743;15255.771163432557;15311.157886316249;15380.923095384564;15546.533603991898;15986.840131598652;12242.081843861746;12523.812142817866;12597;12599.37003149848;13082.803757943639;4699.67102302839;4753.3107699383745;4754.9286760698615;4837.685550439211;4882.072406242803;67845.66077169582;70842.64578677074;70902.64548677276;77229.61385193095;77843;12460.563880264148;12675.366231688378;12827.807582886264;12836.743265134832;13144.868551314556;23259.651105233435;23281.301560953198;23285;23330.066797327916;23340.416489587897;9341.252699784001;9501.809963800686;9668.323217374857;9675.322727409013;9793.069658382443;5246.97376513115;5262.289590905255;5280.656757310762;5294.814681486207;5491.2312276281355;3902.785346805896;4041.4544036555276;4053.8175782089875;4056.249593825152;4078.286299897506;648.3322178156507;673.8079647300594;678.7929681447107;684.551618689759;700.8247938015496;784.744957888677;797.5493845977004;821.8191997760483;822.8889099971547;844.2233145506149;10466.058054774954;10851.186161037796;10902.563897444013;10918.807973942365;11032.393218372907;732.2421293960725;737.9040724705754;748.9101307842984;789.9565523896241;791.7268542352879;4877.463479017305;5116.923246151311;5126.53861152498;5153.098207813618;5200.453952335026;119755;130340;130912;133141;135125.32437337752;113215;113920;114329;114603.42698286455;117659.41170294266;98841;99685.50157249167;100636;101413;103300.48349758354;10012.64955726546;10089.747756306151;10096.34373765703;10151.390916545031;10167.18662506983;49489;50946.74526627343;53732.73133634463;53852.19221711599;61943.38056619376;76631;77674;82347.17652823395;82442;85928;12627.242365458102;12986.155899866479;13245.139065960682;13841.169529828243;14136.293185340486;16143.919280403523;16291.266892990081;16297.511074667778;16512.587185320463;17078.74381884248;15424.074555526704;15454.150021748912;15515.301811418287;15741.134237616583;16084.517464476085;9582.137607615348;9853.458059806779;13292.667683307995;13719.45122195101;14912.850871491146;14178.503752368617;15253.694926101194;15607;15908.443204487787;16168.514944551682;11013.724656883642;11157.218994670295;11278.154138439486;11539.942300288445;11741.354225517678;10982.624385672792;12533.561325353568;12709.110362274552;12893.48426062947;13027.609171724864 03:57:18 INFO - PROCESS | 18766 | __end_tp_report 03:57:18 INFO - PROCESS | 18766 | __start_cc_report 03:57:18 INFO - PROCESS | 18766 | _x_x_mozilla_cycle_collect,1055 03:57:18 INFO - PROCESS | 18766 | __end_cc_report 03:57:18 INFO - PROCESS | 18766 | __startTimestamp1470653838281__endTimestamp 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | ------- Summary: start ------- 03:57:18 INFO - PROCESS | 18766 | Number of tests: 6 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | [#0] dojo.html Cycles:165 Average:5700.74 Median:5926.05 stddev:645.39 (10.9%) stddev-sans-first:617.87 03:57:18 INFO - PROCESS | 18766 | Values: 3241.5 3269.5 3274.5 3291.6 3317.3 5696.8 5792.9 5793.7 5817.9 5841.3 5693.3 5738.9 5743.3 5752.8 5760.8 5157.5 5196.3 5214.7 5218.3 5274.2 5276.7 5292.4 5311.9 5324.4 5329.3 5207.2 5226.9 5261.1 5264.0 5327.8 5329.9 5333.2 5342.9 5349.1 5354.4 5070.3 5090.8 5099.1 5224.7 5241.4 5610.0 5677.7 5679.3 5693.2 5715.3 6119.3 6138.2 6143.7 6143.7 6143.8 5980.3 6066.9 6112.6 6132.8 6180.6 5772.0 5781.0 5793.8 5794.6 5797.6 6316.4 6321.3 6328.1 6329.7 6334.3 5851.5 6006.6 6077.2 6079.7 6108.7 6198.2 6211.4 6212.0 6218.8 6220.8 6123.8 6173.5 6193.3 6194.2 6196.1 5957.7 6054.2 6123.8 6131.2 6148.9 4697.6 4733.5 4737.9 4753.8 4764.7 4204.3 4216.7 4218.5 4227.4 4237.3 5697.2 5719.9 5761.5 5766.8 5794.7 6106.1 6110.6 6111.1 6118.7 6132.7 5781.1 5784.6 5785.6 5791.6 5792.9 5103.8 5104.3 5166.5 5174.8 5196.4 6217.4 6226.8 6233.3 6248.6 6249.1 5948.2 6066.7 6067.9 6069.1 6072.0 6158.5 6294.7 6348.4 6362.5 6429.8 6129.0 6132.5 6132.7 6142.9 6145.0 6163.3 6213.5 6216.5 6216.7 6218.1 5843.3 5969.6 6030.6 6038.8 6095.3 6014.3 6052.6 6054.1 6055.2 6062.6 5953.5 5987.0 5987.2 5997.5 6012.2 5926.1 5928.4 5994.3 5995.4 6051.3 5818.6 5822.0 5831.7 5835.2 5836.3 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | [#1] ext.html Cycles:180 Average:70593.86 Median:4049.85 stddev:143577.79 (3545.3%) stddev-sans-first:143884.45 03:57:18 INFO - PROCESS | 18766 | Values: 622.4 627.4 663.9 665.8 694.5 34845.0 45790.5 47414.8 47489.5 47548.5 48006.5 48417.0 48482.0 48533.3 48545.8 8486.4 9225.4 9383.3 9388.2 11382.6 11153.8 11275.5 11279.5 11284.5 11286.0 11327.7 11364.7 11367.5 11371.1 11384.1 11300.7 11334.3 11360.7 11362.0 11366.1 11093.9 11117.1 11177.7 11194.1 11195.9 44997.6 45270.0 45487.8 45543.3 45559.3 3836.0 3853.5 3858.9 4033.8 4065.9 34905.0 35184.6 35324.0 35387.3 36164.3 4289.7 4290.9 4342.6 4361.6 4387.6 385641.0 546436.0 553480.0 556448.0 556896.0 60997.0 61283.4 61451.4 61462.0 61471.7 39332.0 40284.0 40315.4 68644.7 69020.0 19473.4 19720.3 19744.8 19753.4 19756.7 13168.9 14907.5 15074.7 15155.8 15206.7 20373.0 20557.2 20593.4 20681.7 20697.8 7172.3 7236.0 7405.9 7433.7 7448.5 1006.9 1082.4 1118.7 1164.2 1221.6 35915.8 36081.1 36347.3 36399.6 36447.3 1129.4 1154.5 1158.6 1177.5 1226.2 16483.9 17751.6 17992.7 18110.5 18135.0 582730.0 582838.0 588725.0 589817.0 590265.0 62775.4 63462.0 63509.7 63539.7 63588.7 1362.8 1410.5 1417.5 1515.9 1517.9 30487.2 37933.0 38643.8 38677.0 38836.2 61111.7 61895.4 61969.4 62079.4 62135.7 500730.0 509161.0 510652.0 510927.0 511542.0 30697.8 30862.0 31512.8 31850.0 33258.8 53437.5 53565.5 54052.5 54330.2 55305.0 44581.8 44819.6 45083.8 45852.0 46631.8 44311.0 46333.5 46752.1 47041.5 47144.8 44628.3 44690.8 44706.8 44728.3 45115.3 38860.0 40689.8 40918.2 40961.0 41448.6 30644.2 30874.5 30929.8 31042.4 31194.0 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | [#2] jquery.html Cycles:180 Average:31826.32 Median:24743.69 stddev:93346.72 (377.3%) stddev-sans-first:93578.71 03:57:18 INFO - PROCESS | 18766 | Values: 378.4 404.3 430.2 434.6 437.4 15148.4 20200.5 20708.6 20762.9 21365.3 20019.5 20023.3 20085.6 20273.6 20448.4 5848.6 5905.4 5984.3 6025.7 6037.5 5907.5 5961.0 6046.3 6047.4 6048.7 5904.7 5919.2 5933.6 5955.1 5960.0 5744.2 5898.9 5987.8 5993.3 5994.0 5343.6 5363.3 5373.4 5375.6 5377.0 24397.6 24441.3 24507.6 24728.9 24758.5 2492.2 2515.8 2584.2 2660.7 3240.6 14681.3 15005.2 15180.0 15283.2 15486.2 1990.9 2061.4 2076.8 2082.7 2100.8 82449.0 83255.0 84683.0 85848.6 87167.6 11135.6 11174.8 11302.8 11366.7 11444.7 15270.5 15357.6 15381.8 15382.1 15398.2 8389.9 8528.4 8821.2 8838.6 8959.7 8506.1 8554.0 8617.9 8800.7 8808.0 7106.2 7140.0 7167.6 7170.6 7183.6 2043.3 2044.9 2049.3 2060.9 2061.0 991.0 1022.1 1056.3 1084.6 1091.9 20140.2 20832.6 21199.7 21458.4 21494.1 1023.5 1040.6 1064.8 1111.4 1151.9 11292.9 12190.8 12210.3 12214.7 12286.6 559097.0 565946.0 567049.0 569277.0 583093.0 86832.0 88005.6 89257.0 89374.0 89385.6 1036.9 1086.3 1104.1 1107.0 1108.1 18167.0 18550.4 18784.4 19203.5 19344.9 40916.8 42385.6 42438.4 42955.8 47286.1 58752.0 58811.0 59195.1 61121.4 61814.7 8746.1 8825.1 8966.7 8993.0 9014.9 13214.4 13353.0 13614.4 14044.6 14251.7 11147.9 11402.7 11773.6 11801.5 12519.9 12048.2 12124.8 12170.6 12268.3 12372.3 12249.2 12424.7 12516.6 12566.5 12589.0 7919.5 8151.4 8302.3 9267.3 9363.4 8938.4 8939.7 10869.4 12068.3 12670.7 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | [#3] mootools.html Cycles:180 Average:6333.25 Median:4812.85 stddev:3724.16 (77.4%) stddev-sans-first:3716.04 03:57:18 INFO - PROCESS | 18766 | Values: 1384.8 1505.3 1563.5 1716.6 1888.8 9253.5 9598.0 9679.1 9891.9 9931.4 9652.0 9807.6 9819.5 9874.0 9884.3 2500.8 2513.8 2518.0 2520.2 2520.5 2422.4 2521.9 2527.6 2529.4 2530.9 2443.8 2472.2 2504.0 2510.7 2511.6 2449.1 2455.4 2463.8 2497.9 2499.2 2404.2 2432.0 2437.2 2437.7 2443.9 9757.5 9839.1 9916.2 9934.0 9939.8 5707.7 5842.6 5848.6 5851.6 5857.6 3554.6 3578.2 3605.5 3626.2 3643.7 1097.6 1097.9 1103.1 1112.4 1136.9 13869.2 13939.6 13944.9 13971.9 14002.3 4771.8 4788.4 4837.3 4935.9 4950.6 10714.6 10732.2 10735.4 10760.8 10771.0 5671.9 5674.4 5680.5 5687.2 5698.4 4627.1 4784.7 5229.5 5233.3 5314.4 4195.3 4216.6 4242.9 4283.5 4285.2 1221.3 1228.5 1247.2 1255.6 1256.0 894.5 944.5 987.7 1037.9 1046.2 6424.5 6439.8 6631.7 6634.7 6823.0 498.3 500.0 510.2 512.7 515.1 4426.2 4446.3 4601.1 4710.6 4729.3 12223.1 12564.6 12651.1 12655.0 12660.7 11458.3 11927.4 11948.8 12574.1 12588.3 8966.8 9055.1 9109.8 9170.4 9181.2 6412.3 6521.8 6552.6 6581.9 6662.5 11075.4 11342.6 11360.5 11511.4 11578.4 10495.9 11133.7 11645.1 11677.2 11806.9 8186.8 8422.7 8465.0 8496.6 8517.0 9133.0 9138.5 9146.4 9186.4 9189.9 7819.5 8019.9 8028.9 8105.1 8127.6 7892.3 7941.7 8018.1 8024.6 8393.4 7535.8 7628.8 7646.0 7651.3 7656.0 5441.4 5524.4 5539.3 5593.9 5650.3 5770.9 5808.6 5829.0 5840.6 5879.9 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | [#4] prototype.html Cycles:180 Average:4870.70 Median:5535.63 stddev:1397.43 (25.2%) stddev-sans-first:1359.69 03:57:18 INFO - PROCESS | 18766 | Values: 358.9 361.2 363.7 364.4 368.8 5512.8 5570.3 5583.0 5587.9 5588.8 5366.0 5432.8 5434.1 5535.8 5539.0 4341.1 4364.5 4372.5 4373.6 4388.4 4347.7 4349.5 4383.6 4383.9 4389.6 4339.0 4356.6 4372.2 4399.6 4417.5 4276.5 4303.5 4335.7 4343.9 4367.7 3877.4 3883.7 3887.2 3891.8 3912.2 5290.9 5308.4 5350.5 5368.7 5378.8 4852.4 4927.0 4964.1 4972.3 4994.7 5678.3 5692.8 5703.1 5708.5 5731.5 4285.5 4289.1 4290.9 4294.2 4294.6 5650.7 5690.2 5848.6 5937.4 5963.9 4336.5 4353.2 4358.4 4368.5 4399.5 4437.6 4447.8 4451.1 4451.2 4463.6 4749.4 4754.2 4918.9 4999.2 5011.5 5680.8 5711.4 5713.8 5728.7 5735.6 1486.3 1503.9 1505.7 1513.3 1513.4 1015.1 1032.4 1044.6 1086.8 1101.2 5473.2 5475.2 5488.5 5535.4 5579.3 5747.7 5809.3 5812.6 5816.6 5837.5 5301.7 5517.3 5580.4 5580.7 5641.3 3079.7 3119.8 3124.9 3126.9 3131.7 5843.5 6092.4 6095.5 6104.7 6153.2 6000.2 6027.9 6035.2 6037.1 6041.7 6111.6 6112.3 6144.6 6148.3 6170.8 5608.6 5852.9 5888.5 5912.6 5922.3 5841.6 5862.1 5872.9 5877.3 5898.8 5590.9 5864.2 6058.9 6095.5 6164.6 4153.5 4183.7 4202.4 4209.9 4226.8 5906.1 5960.9 5961.6 5984.5 5991.8 5630.6 5688.3 5738.3 5757.0 5841.2 5683.5 5689.9 5721.9 5728.6 5759.9 5527.9 5654.4 5722.6 5726.1 5734.8 5761.9 5769.3 5771.4 5788.2 5790.6 5647.4 5651.8 5655.1 5662.5 5663.9 03:57:18 INFO - PROCESS | 18766 | 03:57:18 INFO - PROCESS | 18766 | [#5] yui.html Cycles:180 Average:22817.78 Median:13621.60 stddev:33441.74 (245.5%) stddev-sans-first:33494.73 03:57:18 INFO - PROCESS | 18766 | Values: 810.3 856.4 869.8 893.0 916.4 16689.4 17708.5 17894.5 17920.6 17943.4 15787.6 16078.1 16209.0 16242.5 16253.8 878.2 895.8 896.0 897.5 898.7 736.7 865.9 891.7 891.7 895.5 917.1 918.7 918.8 919.2 919.6 915.6 915.7 917.3 917.7 918.3 558.5 572.9 661.5 671.1 672.8 15715.0 16231.5 16266.8 16275.1 16312.3 15255.8 15311.2 15380.9 15546.5 15986.8 12242.1 12523.8 12597.0 12599.4 13082.8 4699.7 4753.3 4754.9 4837.7 4882.1 67845.7 70842.6 70902.6 77229.6 77843.0 12460.6 12675.4 12827.8 12836.7 13144.9 23259.7 23281.3 23285.0 23330.1 23340.4 9341.3 9501.8 9668.3 9675.3 9793.1 5247.0 5262.3 5280.7 5294.8 5491.2 3902.8 4041.5 4053.8 4056.2 4078.3 648.3 673.8 678.8 684.6 700.8 784.7 797.5 821.8 822.9 844.2 10466.1 10851.2 10902.6 10918.8 11032.4 732.2 737.9 748.9 790.0 791.7 4877.5 5116.9 5126.5 5153.1 5200.5 119755.0 130340.0 130912.0 133141.0 135125.3 113215.0 113920.0 114329.0 114603.4 117659.4 98841.0 99685.5 100636.0 101413.0 103300.5 10012.6 10089.7 10096.3 10151.4 10167.2 49489.0 50946.7 53732.7 53852.2 61943.4 76631.0 77674.0 82347.2 82442.0 85928.0 12627.2 12986.2 13245.1 13841.2 14136.3 16143.9 16291.3 16297.5 16512.6 17078.7 15424.1 15454.2 15515.3 15741.1 16084.5 9582.1 9853.5 13292.7 13719.5 14912.9 14178.5 15253.7 15607.0 15908.4 16168.5 11013.7 11157.2 11278.2 11539.9 11741.4 10982.6 12533.6 12709.1 12893.5 13027.6 03:57:18 INFO - PROCESS | 18766 | -------- Summary: end -------- 03:57:18 INFO - PROCESS | 18766 | 03:57:19 INFO - TEST-INFO | 18766: exit 0 03:57:19 INFO - TEST-OK | dromaeo_css | took 1113882ms 03:57:19 INFO - TEST-START | kraken 03:57:19 INFO - operating with platform_type : linux_ 03:57:19 INFO - Initialising browser for kraken test... 03:57:19 INFO - TEST-INFO | started process 18970 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7ZiSIn/profile http://localhost:47975/getInfo.html) 03:57:23 INFO - PROCESS | 18970 | __metrics Screen width/height:1600/1200 03:57:23 INFO - PROCESS | 18970 | colorDepth:24 03:57:23 INFO - PROCESS | 18970 | Browser inner width/height: 1024/697 03:57:23 INFO - PROCESS | 18970 | __metrics 03:57:24 INFO - PROCESS | 18970 | 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:57:24 INFO - PROCESS | 18970 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:57:25 INFO - TEST-INFO | 18970: exit 0 03:57:25 INFO - Browser initialized. 03:57:25 INFO - Running cycle 1/1 for kraken test... 03:57:25 INFO - TEST-INFO | started process 19057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7ZiSIn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:36 INFO - PROCESS | 19057 | RSS: Main: 145342464 03:57:36 INFO - PROCESS | 19057 | 03:57:39 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47975/tests/kraken/driver.html?testName=audio-beat-detection) 03:57:40 INFO - PROCESS | 19057 | RSS: Main: 191696896 03:57:40 INFO - PROCESS | 19057 | 03:57:42 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47975/tests/kraken/driver.html?testName=audio-dft) 03:57:43 INFO - PROCESS | 19057 | RSS: Main: 180297728 03:57:43 INFO - PROCESS | 19057 | 03:57:46 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47975/tests/kraken/driver.html?testName=audio-fft) 03:57:46 INFO - PROCESS | 19057 | RSS: Main: 269733888 03:57:46 INFO - PROCESS | 19057 | 03:57:48 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47975/tests/kraken/driver.html?testName=audio-oscillator) 03:57:49 INFO - PROCESS | 19057 | RSS: Main: 176087040 03:57:49 INFO - PROCESS | 19057 | 03:57:51 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47975/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:57:52 INFO - PROCESS | 19057 | RSS: Main: 177176576 03:57:52 INFO - PROCESS | 19057 | 03:57:55 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47975/tests/kraken/driver.html?testName=imaging-darkroom) 03:57:55 INFO - PROCESS | 19057 | RSS: Main: 213565440 03:57:55 INFO - PROCESS | 19057 | 03:57:59 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47975/tests/kraken/driver.html?testName=imaging-desaturate) 03:57:59 INFO - PROCESS | 19057 | RSS: Main: 212234240 03:57:59 INFO - PROCESS | 19057 | 03:58:02 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47975/tests/kraken/driver.html?testName=json-parse-financial) 03:58:03 INFO - PROCESS | 19057 | RSS: Main: 211849216 03:58:03 INFO - PROCESS | 19057 | 03:58:04 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47975/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:58:05 INFO - PROCESS | 19057 | RSS: Main: 176406528 03:58:05 INFO - PROCESS | 19057 | 03:58:06 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:58:07 INFO - PROCESS | 19057 | RSS: Main: 181841920 03:58:07 INFO - PROCESS | 19057 | 03:58:09 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:58:10 INFO - PROCESS | 19057 | RSS: Main: 187285504 03:58:10 INFO - PROCESS | 19057 | 03:58:12 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:58:13 INFO - PROCESS | 19057 | RSS: Main: 182136832 03:58:13 INFO - PROCESS | 19057 | 03:58:15 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:58:16 INFO - PROCESS | 19057 | RSS: Main: 184815616 03:58:16 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | Cycle 1(1): loaded http://localhost:47975/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47975/tests/kraken/driver.html?testName=ai-astar) 03:58:18 INFO - PROCESS | 19057 | RSS: Main: 235966464 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | __start_tp_report 03:58:18 INFO - PROCESS | 19057 | _x_x_mozilla_page_load 03:58:18 INFO - PROCESS | 19057 | _x_x_mozilla_page_load_details 03:58:18 INFO - PROCESS | 19057 | |i|pagename|runs| 03:58:18 INFO - PROCESS | 19057 | |0;ai-astar;100;110;110;111;111;109;110;111;109;109 03:58:18 INFO - PROCESS | 19057 | |1;audio-beat-detection;150;115;120;164;116;123;140;115;161;116 03:58:18 INFO - PROCESS | 19057 | |2;audio-dft;161;160;160;161;160;160;163;160;159;160 03:58:18 INFO - PROCESS | 19057 | |3;audio-fft;74;76;75;131;75;74;75;75;102;75 03:58:18 INFO - PROCESS | 19057 | |4;audio-oscillator;83;83;121;82;83;83;115;83;83;132 03:58:18 INFO - PROCESS | 19057 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;117;116 03:58:18 INFO - PROCESS | 19057 | |6;imaging-darkroom;146;146;147;146;146;147;146;146;147;147 03:58:18 INFO - PROCESS | 19057 | |7;imaging-desaturate;95;95;95;95;94;94;94;94;95;95 03:58:18 INFO - PROCESS | 19057 | |8;json-parse-financial;50;52;49;49;50;49;49;50;50;49 03:58:18 INFO - PROCESS | 19057 | |9;json-stringify-tinderbox;62;60;60;60;60;61;60;60;62;63 03:58:18 INFO - PROCESS | 19057 | |10;stanford-crypto-aes;79;81;81;71;80;75;71;71;72;74 03:58:18 INFO - PROCESS | 19057 | |11;stanford-crypto-ccm;119;127;124;134;127;120;119;118;160;127 03:58:18 INFO - PROCESS | 19057 | |12;stanford-crypto-pbkdf2;151;150;148;153;150;152;151;153;150;149 03:58:18 INFO - PROCESS | 19057 | |13;stanford-crypto-sha256-iterative;61;61;70;61;64;63;61;61;63;64 03:58:18 INFO - PROCESS | 19057 | __end_tp_report 03:58:18 INFO - PROCESS | 19057 | __start_cc_report 03:58:18 INFO - PROCESS | 19057 | _x_x_mozilla_cycle_collect,1639 03:58:18 INFO - PROCESS | 19057 | __end_cc_report 03:58:18 INFO - PROCESS | 19057 | __startTimestamp1470653898407__endTimestamp 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | ------- Summary: start ------- 03:58:18 INFO - PROCESS | 19057 | Number of tests: 14 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:3.27 (3.0%) stddev-sans-first:0.87 03:58:18 INFO - PROCESS | 19057 | Values: 100.0 110.0 110.0 111.0 111.0 109.0 110.0 111.0 109.0 109.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:131.50 stddev:19.91 (15.1%) stddev-sans-first:20.02 03:58:18 INFO - PROCESS | 19057 | Values: 150.0 115.0 120.0 164.0 116.0 123.0 140.0 115.0 161.0 116.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 03:58:18 INFO - PROCESS | 19057 | Values: 161.0 160.0 160.0 161.0 160.0 160.0 163.0 160.0 159.0 160.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:18.84 (25.1%) stddev-sans-first:19.69 03:58:18 INFO - PROCESS | 19057 | Values: 74.0 76.0 75.0 131.0 75.0 74.0 75.0 75.0 102.0 75.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:83.00 stddev:19.66 (23.7%) stddev-sans-first:20.38 03:58:18 INFO - PROCESS | 19057 | Values: 83.0 83.0 121.0 82.0 83.0 83.0 115.0 83.0 83.0 132.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:58:18 INFO - PROCESS | 19057 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:58:18 INFO - PROCESS | 19057 | Values: 146.0 146.0 147.0 146.0 146.0 147.0 146.0 146.0 147.0 147.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 03:58:18 INFO - PROCESS | 19057 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:0.95 (1.9%) stddev-sans-first:1.00 03:58:18 INFO - PROCESS | 19057 | Values: 50.0 52.0 49.0 49.0 50.0 49.0 49.0 50.0 50.0 49.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:60.50 stddev:1.14 (1.9%) stddev-sans-first:1.12 03:58:18 INFO - PROCESS | 19057 | Values: 62.0 60.0 60.0 60.0 60.0 61.0 60.0 60.0 62.0 63.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:77.00 stddev:4.33 (5.6%) stddev-sans-first:4.40 03:58:18 INFO - PROCESS | 19057 | Values: 79.0 81.0 81.0 71.0 80.0 75.0 71.0 71.0 72.0 74.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:127.00 stddev:12.48 (9.8%) stddev-sans-first:12.86 03:58:18 INFO - PROCESS | 19057 | Values: 119.0 127.0 124.0 134.0 127.0 120.0 119.0 118.0 160.0 127.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:151.00 stddev:1.64 (1.1%) stddev-sans-first:1.73 03:58:18 INFO - PROCESS | 19057 | Values: 151.0 150.0 148.0 153.0 150.0 152.0 151.0 153.0 150.0 149.0 03:58:18 INFO - PROCESS | 19057 | 03:58:18 INFO - PROCESS | 19057 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:2.81 (4.5%) stddev-sans-first:2.89 03:58:18 INFO - PROCESS | 19057 | Values: 61.0 61.0 70.0 61.0 64.0 63.0 61.0 61.0 63.0 64.0 03:58:18 INFO - PROCESS | 19057 | -------- Summary: end -------- 03:58:18 INFO - PROCESS | 19057 | 03:58:19 INFO - TEST-INFO | 19057: exit 0 03:58:19 INFO - TEST-OK | kraken | took 60319ms 03:58:19 INFO - SUITE-END | took 1174s 03:58:19 INFO - Completed test suite (00:19:34) 03:58:19 INFO - javascript benchmark 03:58:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3241.546183534305, 3269.4605390110646, 3274.5251938468914, 3291.637919828817, 3317.3365326934604, 5696.829095127147, 5792.899349123663, 5793.71031448428, 5817.912731309034, 5841.269841269841, 5693.3452652944925, 5738.885222295552, 5743.253377060976, 5752.7986520471795, 5760.827175184741, 5157.5358217760395, 5196.272521846944, 5214.66104703195, 5218.269442278084, 5274.156135018397, 5276.657017293864, 5292.417834038252, 5311.9468805312035, 5324.440933701963, 5329.25390445338, 5207.192885102796, 5226.895462090756, 5261.1319132343115, 5264.0261551613075, 5327.786888524454, 5329.893402131955, 5333.2000199969925, 5342.866428339303, 5349.117395629716, 5354.41101478837, 5070.29015937769, 5090.847274581768, 5099.056674515189, 5224.660397074216, 5241.3710354757295, 5610.046292130348, 5677.716114194315, 5679.290088738908, 5693.231413759131, 5715.31416230055, 6119.326874043852, 6138.202033735631, 6143.692815359259, 6143.723532441051, 6143.846403839895, 5980.28236611605, 6066.908996365058, 6112.572119951606, 6132.845047373404, 6180.567360284782, 5772.0, 5781.046127388976, 5793.797217097382, 5794.565407594448, 5797.623154495029, 6316.368363163647, 6321.273053598895, 6328.145700330397, 6329.746810127635, 6334.3032266450655, 5851.502622277154, 6006.579539432198, 6077.24034495688, 6079.726412311456, 6108.731828471747, 6198.2252218472695, 6211.409916057967, 6211.9689401553605, 6218.81343559694, 6220.813375598739, 6123.84690382744, 6173.47525460332, 6193.349698281616, 6194.163787888578, 6196.132541444202, 5957.702114894238, 6054.212952316171, 6123.8162855114415, 6131.172291740647, 6148.907766383508, 4697.600703940203, 4733.5266473352385, 4737.857864264078, 4753.762311884392, 4764.666473346832, 4204.306289462265, 4216.746995180299, 4218.514870789837, 4227.429297044921, 4237.300845360481, 5697.173909783059, 5719.885602287931, 5761.510271626875, 5766.798162064308, 5794.710264486759, 6106.084087386927, 6110.572259941761, 6111.113888486145, 6118.694065296718, 6132.6627035513475, 5781.132830075522, 5784.623999440023, 5785.566082543826, 5791.5656325775735, 5792.88414231713, 5103.821366252163, 5104.310918026019, 5166.535011848951, 5174.818881339135, 5196.350456192976, 6217.378262173746, 6226.813195604139, 6233.252009758858, 6248.625082495156, 6249.0626406039455, 5948.196993405835, 6066.666333351708, 6067.939320606914, 6069.089636554552, 6071.9981203101, 6158.538109641705, 6294.71673774672, 6348.396902294459, 6362.491000720024, 6429.810485060279, 6128.9693551531955, 6132.47873930703, 6132.662703551169, 6142.938570614237, 6145.047517634774, 6163.322034576104, 6213.502919766317, 6216.502679785516, 6216.720247588779, 6218.067289906369, 5843.29880414336, 5969.582129250911, 6030.6080104793045, 6038.788642397495, 6095.268567771718, 6014.278286605635, 6052.606580572248, 6054.0918862169265, 6055.212822333068, 6062.605930614496, 5953.493953014043, 5987.0, 5987.221661184019, 5997.490213331741, 6012.188354572077, 5926.051831706906, 5928.3775203603, 5994.250718660168, 5995.430434108753, 6051.304100028553, 5818.592698511064, 5822.039363504974, 5831.679257640871, 5835.212246346859, 5836.270466191726], "value": 5656.851838383445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.37140488107, 627.4447114303841, 663.8771827211966, 665.7569986954763, 694.4964900447173, 34845.0, 45790.54209457905, 47414.76292618541, 47489.525104748944, 47548.52451475484, 48006.51993480064, 48417.03165936679, 48482.0, 48533.272000919926, 48545.757271213595, 8486.448380855256, 9225.400348977311, 9383.296252781034, 9388.202002829768, 11382.601608943689, 11153.776924461506, 11275.492602832852, 11279.49242284095, 11284.548618055267, 11286.0, 11327.660170194908, 11364.715882102932, 11367.488463019143, 11371.147163962694, 11384.089272858193, 11300.660980170567, 11334.263272887236, 11360.715982100432, 11362.0, 11366.090712743002, 11093.889061109367, 11117.055050320732, 11177.66467005991, 11194.10447164225, 11195.888041119566, 44997.550024499666, 45270.0, 45487.77256113698, 45543.316850247276, 45559.31661025087, 3835.9808200959096, 3853.4797802296894, 3858.9228215435533, 4033.7983100845136, 4065.8983525411804, 34905.0, 35184.64815351839, 35324.0, 35387.29225415503, 36164.276714465566, 4289.742615443052, 4290.904037797262, 4342.609165175118, 4361.563843615645, 4387.583179597933, 385641.0, 546436.0, 553480.0, 556448.0, 556896.0, 60997.0, 61283.387166127766, 61451.385486144565, 61462.0, 61471.69264153651, 39332.0, 40284.0, 40315.395269070985, 68644.6567767168, 69020.0, 19473.415797526097, 19720.30978915731, 19744.802551974582, 19753.40739777809, 19756.70364944527, 13168.934155329354, 14907.478238261609, 15074.698506029818, 15155.772663410058, 15206.695866082617, 20372.981350932394, 20557.177712891316, 20593.38219853407, 20681.68977465339, 20697.793022069585, 7172.282771722786, 7236.0, 7405.927139745465, 7433.739819106413, 7448.519787784554, 1006.8691070160978, 1082.4479515447022, 1118.6644006797928, 1164.1501703756305, 1221.6179739599957, 35915.820420897726, 36081.09797255037, 36347.27305453876, 36399.63600364015, 36447.27105457876, 1129.4465711801158, 1154.503563467717, 1158.6060739348661, 1177.4524845946676, 1226.2274766896976, 16483.91758041226, 17751.644967100587, 17992.730109048374, 18110.547236318933, 18135.0, 582730.0, 582838.0, 588725.0, 589817.0, 590265.0, 62775.37224627787, 63462.0, 63509.68245158745, 63539.6823015882, 63588.68205658942, 1362.768329384007, 1410.5274732964367, 1417.4826188441145, 1515.9393624255126, 1517.87856971442, 30487.237819054702, 37933.0, 38643.80678096704, 38677.0, 38836.22327553377, 61111.69444152751, 61895.38104618896, 61969.38030619817, 62079.37920620736, 62135.6893215531, 500730.0, 509161.0, 510652.0, 510927.0, 511542.0, 30697.846510767304, 30862.0, 31512.842435788592, 31850.0, 33258.83370583132, 53437.46562534325, 53565.46434535761, 54052.45947540474, 54330.18504722353, 55305.0, 44581.77709111434, 44819.55180448154, 45083.77458112688, 45852.0, 46631.76684116694, 44311.0, 46333.53666463292, 46752.06495869996, 47041.52958470371, 47144.7642761784, 44628.33057504075, 44690.776546117064, 44706.77646611746, 44728.316301022045, 45115.323270150315, 38860.0, 40689.796551017054, 40918.1816363677, 40961.0, 41448.58551414447, 30644.233894152825, 30874.53688194634, 30929.845350773103, 31042.379152417278, 31194.0], "value": 22474.191533572062, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.38512417321834, 404.28643444320795, 430.24492016511016, 434.6023388599432, 437.35926867139636, 15148.394064237431, 20200.494987625316, 20708.585828283427, 20762.896185519072, 21365.252216172437, 20019.499512512193, 20023.299184528543, 20085.59828803423, 20273.59452810943, 20448.386548403534, 5848.590598658096, 5905.350411454737, 5984.251968503937, 6025.668588227646, 6037.516998640121, 5907.527397808187, 5961.046232602785, 6046.2744470663365, 6047.425494578008, 6048.667323297217, 5904.734286957076, 5919.1713160157615, 5933.614315069528, 5955.106733989893, 5960.016597261447, 5744.224529688522, 5898.911516327258, 5987.760489580412, 5993.250843644544, 5994.010988186966, 5343.572514198893, 5363.302770639831, 5373.408925018284, 5375.623706340558, 5376.973115134399, 24397.634035489482, 24441.26676199717, 24507.632385514233, 24728.876355618107, 24758.504829903482, 2492.1526680928573, 2515.8364706294033, 2584.160147951923, 2660.734104962733, 3240.570674026799, 14681.339339729739, 15005.24973751308, 15180.0, 15283.159426231552, 15486.225688715633, 1990.9004549772599, 2061.4228016155353, 2076.8390747542876, 2082.739657542807, 2100.842436817245, 82449.0, 83255.0, 84683.0, 85848.57075714644, 87167.56416217878, 11135.554577816796, 11174.832377514344, 11302.773944521063, 11366.715832104264, 11444.713882153013, 15270.541883743506, 15357.616059598377, 15381.846181538041, 15382.077075375513, 15398.153101579297, 8389.916100839035, 8528.403011789114, 8821.161989610977, 8838.602262898185, 8959.686410975584, 8506.149385061568, 8554.0, 8617.8813925193, 8800.691975780817, 8808.0, 7106.2183159852375, 7140.0, 7167.569945803269, 7170.6056166910275, 7183.640817959081, 2043.2746375036647, 2044.9284275050302, 2049.282751037125, 2060.8763474191596, 2061.0, 990.9900900990898, 1022.1311884897926, 1056.265895202842, 1084.5715942202785, 1091.9332564459262, 20140.19439222444, 20832.58334833325, 21199.68200476994, 21458.356249312546, 21494.14023439045, 1023.5240613114788, 1040.5785656809005, 1064.8402739589123, 1111.394290111891, 1151.8732939376662, 11292.943535282271, 12190.81713774294, 12210.26738395699, 12214.708577939504, 12286.631401057804, 559097.0, 565946.0, 567049.0, 569277.0, 583093.0, 86832.0, 88005.55997219973, 89257.0, 89374.0, 89385.55307223422, 1036.9066783989476, 1086.3373342261214, 1104.0836106031975, 1107.0258172807878, 1108.0526150141675, 18167.0, 18550.44348669542, 18784.436466906016, 19203.533687890736, 19344.90327548353, 40916.79541602273, 42385.5761442394, 42438.36342454804, 42955.7852210737, 47286.05427891492, 58752.0, 58811.0, 59195.11207331807, 61121.38878611157, 61814.69092654508, 8746.081661425458, 8825.11748825138, 8966.730998070068, 8993.010768815557, 9014.909850901407, 13214.405351759386, 13353.0, 13614.387352569356, 14044.648883777987, 14251.714965700421, 11147.944260278648, 11402.657920262405, 11773.587924422947, 11801.468933897824, 12519.874801252236, 12048.216865903689, 12124.818127727915, 12170.634880953585, 12268.263904165779, 12372.319522425993, 12249.203801752858, 12424.689382765504, 12516.624501264976, 12566.497340106293, 12589.0, 7919.524828510308, 8151.388645851704, 8302.335813134814, 9267.304952128483, 9363.391379560308, 8938.4190027648, 8939.687110951085, 10869.402182879701, 12068.275903445821, 12670.683232918882], "value": 9848.269562646256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.8268966379203, 1505.2699440771228, 1563.5309407177847, 1716.5536960390302, 1888.8111188881114, 9253.491057991807, 9598.0, 9679.080487353698, 9891.851622225655, 9931.354461959967, 9651.986541413156, 9807.607695692182, 9819.509024548779, 9873.950630246838, 9884.308098433112, 2500.812439067068, 2513.8240323177383, 2517.987410062947, 2520.193538067819, 2520.4707011527603, 2422.382292515406, 2521.8991240350365, 2527.633493143493, 2529.380301826055, 2530.8861101250395, 2443.780059794618, 2472.221250306148, 2504.0109156883022, 2510.7363726808694, 2511.5855883779154, 2449.0938352809485, 2455.4107014316523, 2463.8152138589676, 2497.9375515612073, 2499.2252401755513, 2404.230646193219, 2431.963520547193, 2437.183543512925, 2437.731849496554, 2443.9266821995193, 9757.512124393823, 9839.114479696862, 9916.157126644244, 9933.950330248375, 9939.80120397588, 5707.6575405475805, 5842.591018628697, 5848.619839710405, 5851.64890106595, 5857.648541087548, 3554.5556805399324, 3578.212793185521, 3605.513255710498, 3626.202235508184, 3643.6538528839983, 1097.5993762276712, 1097.8847221041756, 1103.1450625765017, 1112.3826276416585, 1136.8749437561862, 13869.167849929037, 13939.581812545639, 13944.930275348559, 13971.860281397157, 14002.299885005708, 4771.832985845548, 4788.4253889533475, 4837.274408838703, 4935.925961110586, 4950.579200767975, 10714.624988125379, 10732.195085368628, 10735.409552474533, 10760.784784304271, 10771.0, 5671.914921276184, 5674.404187560247, 5680.5171560417, 5687.232223649837, 5698.430156984268, 4627.097715945426, 4784.6889952153, 5229.50319719626, 5233.319668443154, 5314.441983591744, 4195.265828480065, 4216.557261487561, 4242.915141697149, 4283.528811830696, 4285.164392943346, 1221.2829438090503, 1228.5270170984236, 1247.2391840976998, 1255.5793809074062, 1255.993720031394, 894.5437826708426, 944.5088553951906, 987.698751880669, 1037.865077539915, 1046.1909772261351, 6424.518161137933, 6439.8068057958335, 6631.668416579152, 6634.734610615521, 6823.0106634537715, 498.2501335490064, 499.95000499949714, 510.1684951025835, 512.674451723151, 515.1371452816592, 4426.18115648606, 4446.2663660495655, 4601.102784956901, 4710.552497512731, 4729.314249433882, 12223.1443798935, 12564.560240391542, 12651.0511711622, 12655.0, 12660.746785064432, 11458.255213411101, 11927.403629818647, 11948.761024779456, 12574.1198116131, 12588.307643079535, 8966.820663586692, 9055.139761722758, 9109.77225569366, 9170.44977301364, 9181.173694367406, 6412.294647588668, 6521.804345869631, 6552.57408268461, 6581.934180658323, 6662.467002639681, 11075.446227688744, 11342.60300889465, 11360.54557817678, 11511.424428778695, 11578.36319002463, 10495.947520262349, 11133.665990020312, 11645.068394528587, 11677.240979336317, 11806.940965295118, 8186.836263274582, 8422.663093476192, 8465.0, 8496.575171241537, 8517.0, 9133.0, 9138.543072846463, 9146.359754817098, 9186.40288381253, 9189.862152067859, 7819.530828150329, 8019.879701804594, 8028.879566806386, 8105.148959359182, 8127.593620318842, 7892.289693927344, 7941.722039728582, 8018.077921038922, 8024.558649274346, 8393.37049721261, 7535.811604709706, 7628.771136865903, 7646.044244469442, 7651.273129052731, 7656.0, 5441.401445841034, 5524.392316845224, 5539.279893613804, 5593.860153496195, 5650.29371328584, 5770.942290577041, 5808.593398462067, 5828.970855145697, 5840.649561026352, 5879.882402352015], "value": 4915.38107924309, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.8582509908586, 361.2449979542757, 363.65089514066494, 364.44422256059505, 368.8266514738072, 5512.834614961547, 5570.3315602127705, 5582.972085139579, 5587.944120558793, 5588.832335029955, 5366.007288651602, 5432.809851655193, 5434.076207044802, 5535.833924982257, 5539.0, 4341.1317736452675, 4364.541723119074, 4372.519022907478, 4373.584509471595, 4388.429504164462, 4347.717398369112, 4349.521552629209, 4383.561643835623, 4383.890402739925, 4389.6049355558, 4339.0, 4356.607905288539, 4372.234858899696, 4399.604035636792, 4417.5201307562, 4276.5295817460055, 4303.462067241595, 4335.739855608658, 4343.913121737579, 4367.67242456817, 3877.418387241936, 3883.728139030269, 3887.2031233597045, 3891.8324502649375, 3912.197999410114, 5290.92063619046, 5308.389535203424, 5350.491703288181, 5368.677879327252, 5378.784848606051, 4852.42296253719, 4926.955313544875, 4964.081644895669, 4972.254161875711, 4994.650374473788, 5678.296511046711, 5692.800751973702, 5703.117971069537, 5708.514776244025, 5731.512821410185, 4285.528591854893, 4289.099289149282, 4290.871273861789, 4294.162638285536, 4294.634956028742, 5650.6892120933335, 5690.2033715279895, 5848.590598658096, 5937.383803078788, 5963.880722385571, 4336.460556502459, 4353.216421044179, 4358.3680366347335, 4368.497622773358, 4399.472063352418, 4437.64498840092, 4447.755373454426, 4451.10977804434, 4451.1765323415275, 4463.642908567307, 4749.35883655702, 4754.168020596382, 4918.926216106761, 4999.175136102575, 5011.523905228997, 5680.801171958961, 5711.372258906192, 5713.8285851424525, 5728.684922329312, 5735.56238598594, 1486.2642991719167, 1503.8571335723088, 1505.7214415333199, 1513.2585033333587, 1513.43246282644, 1015.0712098429854, 1032.3702541449709, 1044.6239353832614, 1086.842407850873, 1101.2346419238552, 5473.151661492475, 5475.206095116186, 5488.533474654699, 5535.418781028015, 5579.330480342385, 5747.712614369265, 5809.331926828384, 5812.6221795584, 5816.563757718188, 5837.503812175928, 5301.734913254322, 5517.282753242052, 5580.441955804387, 5580.748866301026, 5641.323041234996, 3079.6766339534024, 3119.7816152869536, 3124.9219019438256, 3126.8905588304756, 3131.6711745266875, 5843.503302219274, 6092.39076092396, 6095.451409373178, 6104.664243466652, 6153.169322141453, 6000.21997140369, 6027.9095813562835, 6035.155078289043, 6037.094435834572, 6041.667708276087, 6111.572189946661, 6112.266528016667, 6144.631322120597, 6148.292946311142, 6170.784022559189, 5608.579356548356, 5852.853678657897, 5888.499477544457, 5912.586118971631, 5922.289325280994, 5841.620294680833, 5862.149988251765, 5872.853178670567, 5877.2947246329, 5898.793542226002, 5590.86022849432, 5864.208331875142, 6058.939410605837, 6095.542834287535, 6164.599301045418, 4153.522344930372, 4183.748975061506, 4202.390653355185, 4209.936850947177, 4226.767527786002, 5906.114082887601, 5960.910586341121, 5961.612495187799, 5984.461398474158, 5991.7902873399225, 5630.634008789416, 5688.326486551315, 5738.282714660668, 5757.0, 5841.240638716939, 5683.545316374597, 5689.91891540606, 5721.942780572141, 5728.598998070095, 5759.913601296068, 5527.9220551991975, 5654.406287339934, 5722.62802917809, 5726.141078838207, 5734.827955161351, 5761.8559536011935, 5769.278840144982, 5771.431712887893, 5788.189653448605, 5790.594658373873, 5647.407022262603, 5651.8304450866535, 5655.123455864347, 5662.490375866192, 5663.915041274302], "value": 4434.2049122508215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.3274282345653, 856.4475913036088, 869.8434281829271, 892.9642814287427, 916.3997581584057, 16689.415870444536, 17708.46874593761, 17894.463166105037, 17920.641587168266, 17943.371981980617, 15787.605309867258, 16078.115703636295, 16209.0, 16242.51272461828, 16253.756193657104, 878.1525827576387, 895.807401408697, 895.9552022398886, 897.537768049455, 898.7124120281512, 736.7126820539994, 865.8528050231475, 891.6656253904786, 891.6879092317702, 895.4671970177734, 917.1241464401514, 918.701422037837, 918.7978644698155, 919.1773362840254, 919.5953780336664, 915.6291701860757, 915.716128000322, 917.2982668258787, 917.7476194046691, 918.2699753695828, 558.483402852364, 572.928383952006, 661.507177153024, 671.0973740319312, 672.7611697847292, 15715.0, 16231.51305460838, 16266.75599866003, 16275.104869232187, 16312.347506099743, 15255.771163432557, 15311.157886316249, 15380.923095384564, 15546.533603991898, 15986.840131598652, 12242.081843861746, 12523.812142817866, 12597.0, 12599.37003149848, 13082.803757943639, 4699.67102302839, 4753.3107699383745, 4754.9286760698615, 4837.685550439211, 4882.072406242803, 67845.66077169582, 70842.64578677074, 70902.64548677276, 77229.61385193095, 77843.0, 12460.563880264148, 12675.366231688378, 12827.807582886264, 12836.743265134832, 13144.868551314556, 23259.651105233435, 23281.301560953198, 23285.0, 23330.066797327916, 23340.416489587897, 9341.252699784001, 9501.809963800686, 9668.323217374857, 9675.322727409013, 9793.069658382443, 5246.97376513115, 5262.289590905255, 5280.656757310762, 5294.814681486207, 5491.2312276281355, 3902.785346805896, 4041.4544036555276, 4053.8175782089875, 4056.249593825152, 4078.286299897506, 648.3322178156507, 673.8079647300594, 678.7929681447107, 684.551618689759, 700.8247938015496, 784.744957888677, 797.5493845977004, 821.8191997760483, 822.8889099971547, 844.2233145506149, 10466.058054774954, 10851.186161037796, 10902.563897444013, 10918.807973942365, 11032.393218372907, 732.2421293960725, 737.9040724705754, 748.9101307842984, 789.9565523896241, 791.7268542352879, 4877.463479017305, 5116.923246151311, 5126.53861152498, 5153.098207813618, 5200.453952335026, 119755.0, 130340.0, 130912.0, 133141.0, 135125.32437337752, 113215.0, 113920.0, 114329.0, 114603.42698286455, 117659.41170294266, 98841.0, 99685.50157249167, 100636.0, 101413.0, 103300.48349758354, 10012.64955726546, 10089.747756306151, 10096.34373765703, 10151.390916545031, 10167.18662506983, 49489.0, 50946.74526627343, 53732.73133634463, 53852.19221711599, 61943.38056619376, 76631.0, 77674.0, 82347.17652823395, 82442.0, 85928.0, 12627.242365458102, 12986.155899866479, 13245.139065960682, 13841.169529828243, 14136.293185340486, 16143.919280403523, 16291.266892990081, 16297.511074667778, 16512.587185320463, 17078.74381884248, 15424.074555526704, 15454.150021748912, 15515.301811418287, 15741.134237616583, 16084.517464476085, 9582.137607615348, 9853.458059806779, 13292.667683307995, 13719.45122195101, 14912.850871491146, 14178.503752368617, 15253.694926101194, 15607.0, 15908.443204487787, 16168.514944551682, 11013.724656883642, 11157.218994670295, 11278.154138439486, 11539.942300288445, 11741.354225517678, 10982.624385672792, 12533.561325353568, 12709.110362274552, 12893.48426062947, 13027.609171724864], "value": 8244.322285677128, "unit": "score"}], "name": "dromaeo_css", "value": 7798.875008278581, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 110.0, 111.0, 111.0, 109.0, 110.0, 111.0, 109.0, 109.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 120.0, 164.0, 116.0, 123.0, 140.0, 115.0, 161.0, 116.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 160.0, 160.0, 163.0, 160.0, 159.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 131.0, 75.0, 74.0, 75.0, 75.0, 102.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 121.0, 82.0, 83.0, 83.0, 115.0, 83.0, 83.0, 132.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 49.0, 49.0, 50.0, 49.0, 49.0, 50.0, 50.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 62.0, 63.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 81.0, 71.0, 80.0, 75.0, 71.0, 71.0, 72.0, 74.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 127.0, 124.0, 134.0, 127.0, 120.0, 119.0, 118.0, 160.0, 127.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 148.0, 153.0, 150.0, 152.0, 151.0, 153.0, 150.0, 149.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 70.0, 61.0, 64.0, 63.0, 61.0, 61.0, 63.0, 64.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1464.3000000000002, "alertThreshold": 2.0}]} 03:58:19 INFO - Return code: 0 03:58:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:58:19 INFO - # TBPL SUCCESS # 03:58:19 INFO - Running post-action listener: _resource_record_post_action 03:58:19 INFO - [mozharness: 2016-08-08 10:58:19.714567Z] Finished run-tests step (success) 03:58:19 INFO - Running post-run listener: _resource_record_post_run 03:58:19 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 326123520; Read time: 12; Write time: 242760 03:58:19 INFO - TinderboxPrint: CPU usage
12.9% 03:58:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 03:58:19 INFO - TinderboxPrint: I/O write bytes / time
326,123,520 / 242,760 03:58:19 INFO - TinderboxPrint: CPU idle
8,277.4 (87.1%) 03:58:19 INFO - TinderboxPrint: CPU user
1,182.5 (12.4%) 03:58:19 INFO - TinderboxPrint: Swap in / out
0 / 0 03:58:19 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78295040; Read time: 0; Write time: 51100 03:58:19 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 247537664; Read time: 12; Write time: 191640 03:58:20 INFO - Running post-run listener: _upload_blobber_files 03:58:20 INFO - Blob upload gear active. 03:58:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:58:20 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:58:20 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:58:20 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:58:20 INFO - (blobuploader) - INFO - Open directory for files ... 03:58:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:58:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:58:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:21 INFO - (blobuploader) - INFO - Done attempting. 03:58:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:58:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:58:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:22 INFO - (blobuploader) - INFO - Done attempting. 03:58:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:58:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:58:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:27 INFO - (blobuploader) - INFO - Done attempting. 03:58:27 INFO - (blobuploader) - INFO - Iteration through files over. 03:58:27 INFO - Return code: 0 03:58:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:58:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:58:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70731f019ef53d307e6c34179b30fe1ca60450cdc282435c89c0924adf8b8cd90d628f3e4b9921192dbd1fdd606a524bac0df81265e0e8357b909f19d5ab69ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cfa7b198eeae02979c0a945af0aefc1d7aa2e84ea7effb35cbbd8a319c53f3a3a3e25df85d08c541d2c5a7d43d9b827e477efb679f11ea2d51e5cd43495d89b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26973e3d1fe4370ec82f9d2cf6951a186d2d3742ea1f0cb273ca4a8437f2b5d9e8a6d61742b3c8e5aa439093a2444696625485d64fc0f458538b9d6f50444362"} 03:58:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:58:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:58:27 INFO - Contents: 03:58:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70731f019ef53d307e6c34179b30fe1ca60450cdc282435c89c0924adf8b8cd90d628f3e4b9921192dbd1fdd606a524bac0df81265e0e8357b909f19d5ab69ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cfa7b198eeae02979c0a945af0aefc1d7aa2e84ea7effb35cbbd8a319c53f3a3a3e25df85d08c541d2c5a7d43d9b827e477efb679f11ea2d51e5cd43495d89b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26973e3d1fe4370ec82f9d2cf6951a186d2d3742ea1f0cb273ca4a8437f2b5d9e8a6d61742b3c8e5aa439093a2444696625485d64fc0f458538b9d6f50444362"} 03:58:27 INFO - Running post-run listener: copy_logs_to_upload_dir 03:58:27 INFO - Copying logs to upload dir... 03:58:27 INFO - mkdir: /builds/slave/test/build/upload/logs 03:58:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.887038 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-08 03:58:26.551052) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 03:58:26.554722) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413 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/70731f019ef53d307e6c34179b30fe1ca60450cdc282435c89c0924adf8b8cd90d628f3e4b9921192dbd1fdd606a524bac0df81265e0e8357b909f19d5ab69ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cfa7b198eeae02979c0a945af0aefc1d7aa2e84ea7effb35cbbd8a319c53f3a3a3e25df85d08c541d2c5a7d43d9b827e477efb679f11ea2d51e5cd43495d89b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26973e3d1fe4370ec82f9d2cf6951a186d2d3742ea1f0cb273ca4a8437f2b5d9e8a6d61742b3c8e5aa439093a2444696625485d64fc0f458538b9d6f50444362"} build_url:https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/Em_DwoGqSbKcELLrRoQJ8g/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/70731f019ef53d307e6c34179b30fe1ca60450cdc282435c89c0924adf8b8cd90d628f3e4b9921192dbd1fdd606a524bac0df81265e0e8357b909f19d5ab69ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cfa7b198eeae02979c0a945af0aefc1d7aa2e84ea7effb35cbbd8a319c53f3a3a3e25df85d08c541d2c5a7d43d9b827e477efb679f11ea2d51e5cd43495d89b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26973e3d1fe4370ec82f9d2cf6951a186d2d3742ea1f0cb273ca4a8437f2b5d9e8a6d61742b3c8e5aa439093a2444696625485d64fc0f458538b9d6f50444362"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 03:58:26.582442) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:58:26.582727) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470652225.5454-1749582413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004389 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 03:58:26.672497) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 03:58:26.672804) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 03:58:26.673080) ========= ========= Total master_lag: 0.18 =========