builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1470048029.65 results: success (0) buildid: 20160731234823 builduid: ac593188bfc9435eb9125393ed1fd078 revision: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.649378) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.649771) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.650135) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.764040) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.764331) ========= 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=826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 03:40:31-- 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% 46.0M=0s 2016-08-01 03:40:31 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135850 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.924941) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.925259) ========= 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=826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017467 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:29.987392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 03:40:29.987736) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff --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 fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff --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=826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 03:40:31,918 truncating revision to first 12 chars 2016-08-01 03:40:31,918 Setting DEBUG logging. 2016-08-01 03:40:31,918 attempt 1/10 2016-08-01 03:40:31,918 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fcdf4bb70356?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 03:40:33,363 unpacking tar archive at: mozilla-aurora-fcdf4bb70356/testing/mozharness/ program finished with exit code 0 elapsedTime=1.788557 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 03:40:31.793180) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.793518) ========= script_repo_revision: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.793976) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.794283) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 03:40:31.804630) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-08-01 03:40:31.805110) ========= /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=826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:40:33 INFO - MultiFileLogger online at 20160801 03:40:33 in /builds/slave/test 03:40:33 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:40:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:40:33 INFO - {'append_to_log': False, 03:40:33 INFO - 'base_work_dir': '/builds/slave/test', 03:40:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:40:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:40:33 INFO - 'branch': 'Mozilla-Aurora', 03:40:33 INFO - 'buildbot_json_path': 'buildprops.json', 03:40:33 INFO - 'config_files': ('talos/linux_config.py',), 03:40:33 INFO - 'default_actions': ('clobber', 03:40:33 INFO - 'read-buildbot-config', 03:40:33 INFO - 'download-and-extract', 03:40:33 INFO - 'populate-webroot', 03:40:33 INFO - 'create-virtualenv', 03:40:33 INFO - 'install', 03:40:33 INFO - 'run-tests'), 03:40:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:40:33 INFO - 'download_minidump_stackwalk': True, 03:40:33 INFO - 'download_symbols': 'ondemand', 03:40:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:40:33 INFO - 'tooltool.py': '/tools/tooltool.py', 03:40:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:40:33 INFO - '/tools/misc-python/virtualenv.py')}, 03:40:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:40:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:40:33 INFO - 'installer_path': 'installer.exe', 03:40:33 INFO - 'log_level': 'info', 03:40:33 INFO - 'log_name': 'talos', 03:40:33 INFO - 'log_to_console': True, 03:40:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:40:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:40:33 INFO - 'opt_config_files': (), 03:40:33 INFO - 'pip_index': False, 03:40:33 INFO - 'sps_profile': False, 03:40:33 INFO - 'sps_profile_interval': 0, 03:40:33 INFO - 'suite': 'dromaeojs', 03:40:33 INFO - 'system_bits': '32', 03:40:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:40:33 INFO - 'title': 'talos-linux64-ix-052', 03:40:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:40:33 INFO - 'use_talos_json': True, 03:40:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:40:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:40:33 INFO - 'work_dir': 'build'} 03:40:33 INFO - ##### 03:40:33 INFO - ##### Running clobber step. 03:40:33 INFO - ##### 03:40:33 INFO - Running pre-action listener: _resource_record_pre_action 03:40:33 INFO - Running main action method: clobber 03:40:33 INFO - rmtree: /builds/slave/test/build 03:40:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:40:34 INFO - Running post-action listener: _resource_record_post_action 03:40:34 INFO - ##### 03:40:34 INFO - ##### Finished clobber step (success) 03:40:34 INFO - ##### 03:40:34 INFO - ##### 03:40:34 INFO - ##### Running read-buildbot-config step. 03:40:34 INFO - ##### 03:40:34 INFO - Running pre-action listener: _resource_record_pre_action 03:40:34 INFO - Running main action method: read_buildbot_config 03:40:34 INFO - Using buildbot properties: 03:40:34 INFO - { 03:40:34 INFO - "project": "", 03:40:34 INFO - "product": "firefox", 03:40:34 INFO - "script_repo_revision": "production", 03:40:34 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:40:34 INFO - "repository": "", 03:40:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:40:34 INFO - "buildid": "20160731234823", 03:40:34 INFO - "pgo_build": "True", 03:40:34 INFO - "basedir": "/builds/slave/test", 03:40:34 INFO - "buildnumber": 442, 03:40:34 INFO - "slavename": "talos-linux64-ix-052", 03:40:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:40:34 INFO - "platform": "ubuntu64_hw", 03:40:34 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:40:34 INFO - "branch": "mozilla-aurora", 03:40:34 INFO - "repo_path": "releases/mozilla-aurora", 03:40:34 INFO - "revision": "fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff", 03:40:34 INFO - "stage_platform": "linux64-pgo", 03:40:34 INFO - "builduid": "ac593188bfc9435eb9125393ed1fd078", 03:40:34 INFO - "slavebuilddir": "test" 03:40:34 INFO - } 03:40:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:40:34 INFO - Running post-action listener: _resource_record_post_action 03:40:34 INFO - ##### 03:40:34 INFO - ##### Finished read-buildbot-config step (success) 03:40:34 INFO - ##### 03:40:34 INFO - ##### 03:40:34 INFO - ##### Running download-and-extract step. 03:40:34 INFO - ##### 03:40:34 INFO - Running pre-action listener: _resource_record_pre_action 03:40:34 INFO - Running main action method: download_and_extract 03:40:34 INFO - mkdir: /builds/slave/test/build/tests 03:40: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:40:34 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:40:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:40:35 INFO - Downloaded 1361 bytes. 03:40:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:40:35 INFO - Using the following test package requirements: 03:40:35 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:40:35 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:40:35 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:40:35 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'jsshell-linux-x86_64.zip'], 03:40:35 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:40:35 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:40:35 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:40:35 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:40:35 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:40:35 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:40:35 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:40:35 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:35 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:40:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:40:36 INFO - Downloaded 21967710 bytes. 03:40:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:40:37 INFO - Return code: 0 03:40:37 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:37 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:40:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:40:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:41:03 INFO - Downloaded 21967710 bytes. 03:41:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:41:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:41:04 INFO - Return code: 0 03:41:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:04 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:41:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:41:05 INFO - Downloaded 10833369 bytes. 03:41:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:41:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:41:06 INFO - Return code: 0 03:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:06 INFO - https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:41:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:41:08 INFO - Downloaded 61985118 bytes. 03:41:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:08 INFO - mkdir: /builds/slave/test/properties 03:41:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:41:08 INFO - Writing to file /builds/slave/test/properties/build_url 03:41:08 INFO - Contents: 03:41:08 INFO - build_url:https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:41:08 INFO - Running post-action listener: _resource_record_post_action 03:41:08 INFO - Running post-action listener: set_extra_try_arguments 03:41:08 INFO - ##### 03:41:08 INFO - ##### Finished download-and-extract step (success) 03:41:08 INFO - ##### 03:41:08 INFO - ##### 03:41:08 INFO - ##### Running populate-webroot step. 03:41:08 INFO - ##### 03:41:08 INFO - Running pre-action listener: _resource_record_pre_action 03:41:08 INFO - Running main action method: populate_webroot 03:41:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:41:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:41:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:41:08 INFO - '--tppagecycles', 03:41:08 INFO - '7'], 03:41:08 INFO - 'tests': ['tsvgm']}}, 03:41:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:41:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:41:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:41:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:41:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['damp', 'tps']}, 03:41:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'tests': ['damp', 'tps']}, 03:41:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['dromaeo_dom']}, 03:41:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:41:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other-e10s': {'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['a11yr', 03:41:08 INFO - 'ts_paint', 03:41:08 INFO - 'tpaint', 03:41:08 INFO - 'sessionrestore', 03:41:08 INFO - 'sessionrestore_no_auto_restore', 03:41:08 INFO - 'tabpaint']}, 03:41:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tsvgx', 03:41:08 INFO - 'tsvgr_opacity', 03:41:08 INFO - 'tart', 03:41:08 INFO - 'tscrollx', 03:41:08 INFO - 'cart']}, 03:41:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:41:08 INFO - 'tsvgr_opacity', 03:41:08 INFO - 'tart', 03:41:08 INFO - 'tscrollx', 03:41:08 INFO - 'cart']}, 03:41:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s'], 03:41:08 INFO - 'tests': ['tp5o']}, 03:41:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'tests': ['tp5o']}, 03:41:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--disable-e10s', 03:41:08 INFO - '--xperf_path', 03:41:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:08 INFO - 'tests': ['tp5n']}, 03:41:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:08 INFO - 'talos_options': ['--xperf_path', 03:41:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:08 INFO - 'tests': ['tp5n']}}, 03:41:08 INFO - 'talos.zip': {'path': '', 03:41:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:41:08 INFO - Running post-action listener: _resource_record_post_action 03:41:08 INFO - ##### 03:41:08 INFO - ##### Finished populate-webroot step (success) 03:41:08 INFO - ##### 03:41:08 INFO - ##### 03:41:08 INFO - ##### Running create-virtualenv step. 03:41:08 INFO - ##### 03:41:08 INFO - Running pre-action listener: _resource_record_pre_action 03:41:08 INFO - Running main action method: create_virtualenv 03:41:08 INFO - Creating virtualenv /builds/slave/test/build/venv 03:41:08 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:41:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:41:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:41:08 INFO - Using real prefix '/usr' 03:41:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:41:09 INFO - Installing distribute.............................................................................................................................................................................................done. 03:41:11 INFO - Installing pip.................done. 03:41:11 INFO - Return code: 0 03:41:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:41: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:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41: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', 'pip>=1.5'] in /builds/slave/test/build 03:41: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 pip>=1.5 03:41:11 INFO - Using env: {'DISPLAY': ':0', 03:41:11 INFO - 'HOME': '/home/cltbld', 03:41:11 INFO - 'LANG': 'en_US.UTF-8', 03:41:11 INFO - 'LANGUAGE': 'en_US:en', 03:41:11 INFO - 'LOGNAME': 'cltbld', 03:41:11 INFO - 'MAIL': '/var/mail/cltbld', 03:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:11 INFO - 'MOZ_NO_REMOTE': '1', 03:41:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:11 INFO - 'NO_EM_RESTART': '1', 03:41:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:11 INFO - 'PWD': '/builds/slave/test', 03:41:11 INFO - 'SHELL': '/bin/bash', 03:41:11 INFO - 'SHLVL': '1', 03:41:11 INFO - 'TERM': 'linux', 03:41:11 INFO - 'TMOUT': '86400', 03:41:11 INFO - 'USER': 'cltbld', 03:41:11 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:11 INFO - '_': '/tools/buildbot/bin/python'} 03:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:11 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:41:11 INFO - Cleaning up... 03:41:11 INFO - Return code: 0 03:41:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:41: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:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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', '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 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:41: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 psutil>=3.1.1 03:41:11 INFO - Using env: {'DISPLAY': ':0', 03:41:11 INFO - 'HOME': '/home/cltbld', 03:41:11 INFO - 'LANG': 'en_US.UTF-8', 03:41:11 INFO - 'LANGUAGE': 'en_US:en', 03:41:11 INFO - 'LOGNAME': 'cltbld', 03:41:11 INFO - 'MAIL': '/var/mail/cltbld', 03:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:11 INFO - 'MOZ_NO_REMOTE': '1', 03:41:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:11 INFO - 'NO_EM_RESTART': '1', 03:41:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:11 INFO - 'PWD': '/builds/slave/test', 03:41:11 INFO - 'SHELL': '/bin/bash', 03:41:11 INFO - 'SHLVL': '1', 03:41:11 INFO - 'TERM': 'linux', 03:41:11 INFO - 'TMOUT': '86400', 03:41:11 INFO - 'USER': 'cltbld', 03:41:11 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:11 INFO - '_': '/tools/buildbot/bin/python'} 03:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:11 INFO - Downloading/unpacking psutil>=3.1.1 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:41:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:13 INFO - Installing collected packages: psutil 03:41:13 INFO - Running setup.py install for psutil 03:41:13 INFO - building 'psutil._psutil_linux' extension 03:41:13 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:41:14 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:41:14 INFO - building 'psutil._psutil_posix' extension 03:41:14 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:41:14 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:41:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:14 INFO - Successfully installed psutil 03:41:14 INFO - Cleaning up... 03:41:14 INFO - Return code: 0 03:41:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:41: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:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:41: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 mozsystemmonitor==0.3 03:41:14 INFO - Using env: {'DISPLAY': ':0', 03:41:14 INFO - 'HOME': '/home/cltbld', 03:41:14 INFO - 'LANG': 'en_US.UTF-8', 03:41:14 INFO - 'LANGUAGE': 'en_US:en', 03:41:14 INFO - 'LOGNAME': 'cltbld', 03:41:14 INFO - 'MAIL': '/var/mail/cltbld', 03:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:14 INFO - 'MOZ_NO_REMOTE': '1', 03:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:14 INFO - 'NO_EM_RESTART': '1', 03:41:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:14 INFO - 'PWD': '/builds/slave/test', 03:41:14 INFO - 'SHELL': '/bin/bash', 03:41:14 INFO - 'SHLVL': '1', 03:41:14 INFO - 'TERM': 'linux', 03:41:14 INFO - 'TMOUT': '86400', 03:41:14 INFO - 'USER': 'cltbld', 03:41:14 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:14 INFO - '_': '/tools/buildbot/bin/python'} 03:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:41: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:41: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:41: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:41: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:41: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:41: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:41:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:41:16 INFO - Installing collected packages: mozsystemmonitor 03:41:16 INFO - Running setup.py install for mozsystemmonitor 03:41:16 INFO - Successfully installed mozsystemmonitor 03:41:16 INFO - Cleaning up... 03:41:16 INFO - Return code: 0 03:41:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:41: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:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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', '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 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:41: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 jsonschema==2.5.1 03:41:16 INFO - Using env: {'DISPLAY': ':0', 03:41:16 INFO - 'HOME': '/home/cltbld', 03:41:16 INFO - 'LANG': 'en_US.UTF-8', 03:41:16 INFO - 'LANGUAGE': 'en_US:en', 03:41:16 INFO - 'LOGNAME': 'cltbld', 03:41:16 INFO - 'MAIL': '/var/mail/cltbld', 03:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:16 INFO - 'MOZ_NO_REMOTE': '1', 03:41:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:16 INFO - 'NO_EM_RESTART': '1', 03:41:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:16 INFO - 'PWD': '/builds/slave/test', 03:41:16 INFO - 'SHELL': '/bin/bash', 03:41:16 INFO - 'SHLVL': '1', 03:41:16 INFO - 'TERM': 'linux', 03:41:16 INFO - 'TMOUT': '86400', 03:41:16 INFO - 'USER': 'cltbld', 03:41:16 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:16 INFO - '_': '/tools/buildbot/bin/python'} 03:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:16 INFO - Downloading/unpacking jsonschema==2.5.1 03:41: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:41: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:41: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:41: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:41: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:41: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:41:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:41:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:41:18 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:41:18 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:41:18 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:41:18 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:41:18 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:41:18 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:41:18 INFO - Downloading functools32-3.2.3-2.tar.gz 03:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:41:19 INFO - warning: no files found matching '*.txt' 03:41:19 INFO - no previously-included directories found matching 'build' 03:41:19 INFO - no previously-included directories found matching 'dist' 03:41:19 INFO - no previously-included directories found matching '.git*' 03:41:19 INFO - Installing collected packages: jsonschema, functools32 03:41:19 INFO - Running setup.py install for functools32 03:41:19 INFO - warning: no files found matching '*.txt' 03:41:19 INFO - no previously-included directories found matching 'build' 03:41:19 INFO - no previously-included directories found matching 'dist' 03:41:19 INFO - no previously-included directories found matching '.git*' 03:41:19 INFO - Successfully installed jsonschema functools32 03:41:19 INFO - Cleaning up... 03:41:19 INFO - Return code: 0 03:41:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:41:19 INFO - Using env: {'DISPLAY': ':0', 03:41:19 INFO - 'HOME': '/home/cltbld', 03:41:19 INFO - 'LANG': 'en_US.UTF-8', 03:41:19 INFO - 'LANGUAGE': 'en_US:en', 03:41:19 INFO - 'LOGNAME': 'cltbld', 03:41:19 INFO - 'MAIL': '/var/mail/cltbld', 03:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:19 INFO - 'MOZ_NO_REMOTE': '1', 03:41:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:19 INFO - 'NO_EM_RESTART': '1', 03:41:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:19 INFO - 'PWD': '/builds/slave/test', 03:41:19 INFO - 'SHELL': '/bin/bash', 03:41:19 INFO - 'SHLVL': '1', 03:41:19 INFO - 'TERM': 'linux', 03:41:19 INFO - 'TMOUT': '86400', 03:41:19 INFO - 'USER': 'cltbld', 03:41:19 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:19 INFO - '_': '/tools/buildbot/bin/python'} 03:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:19 INFO - Downloading/unpacking blobuploader==1.2.4 03:41: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:41: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:41: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:41: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:41: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:41: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:41:21 INFO - Downloading blobuploader-1.2.4.tar.gz 03:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:41:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:41:21 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:41:21 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:41:21 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:41:21 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:41:21 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:41:21 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:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:41:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:41:21 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:41:21 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:41:21 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:41:21 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:41:21 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:41:21 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:41:22 INFO - Downloading docopt-0.6.1.tar.gz 03:41:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:41:22 INFO - Installing collected packages: blobuploader, requests, docopt 03:41:22 INFO - Running setup.py install for blobuploader 03:41:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:41:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:41:22 INFO - Running setup.py install for requests 03:41:22 INFO - Running setup.py install for docopt 03:41:22 INFO - Successfully installed blobuploader requests docopt 03:41:22 INFO - Cleaning up... 03:41:22 INFO - Return code: 0 03:41:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:22 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 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41:22 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:41:22 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:41:22 INFO - Using env: {'DISPLAY': ':0', 03:41:22 INFO - 'HOME': '/home/cltbld', 03:41:22 INFO - 'LANG': 'en_US.UTF-8', 03:41:22 INFO - 'LANGUAGE': 'en_US:en', 03:41:22 INFO - 'LOGNAME': 'cltbld', 03:41:22 INFO - 'MAIL': '/var/mail/cltbld', 03:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:22 INFO - 'MOZ_NO_REMOTE': '1', 03:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:22 INFO - 'NO_EM_RESTART': '1', 03:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:22 INFO - 'PWD': '/builds/slave/test', 03:41:22 INFO - 'SHELL': '/bin/bash', 03:41:22 INFO - 'SHLVL': '1', 03:41:22 INFO - 'TERM': 'linux', 03:41:22 INFO - 'TMOUT': '86400', 03:41:22 INFO - 'USER': 'cltbld', 03:41:22 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:22 INFO - '_': '/tools/buildbot/bin/python'} 03:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:22 INFO - Running setup.py (path:/tmp/pip-v3500K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:22 INFO - Running setup.py (path:/tmp/pip-Vupuj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:23 INFO - Running setup.py (path:/tmp/pip-JCXsv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:23 INFO - Running setup.py (path:/tmp/pip-oZfKEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:23 INFO - Running setup.py (path:/tmp/pip-B_G9Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:23 INFO - Running setup.py (path:/tmp/pip-Jw1TL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:23 INFO - Running setup.py (path:/tmp/pip-r1Qokd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:23 INFO - Running setup.py (path:/tmp/pip-B02AsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:23 INFO - Running setup.py (path:/tmp/pip-Ri80pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:23 INFO - Running setup.py (path:/tmp/pip-odrAdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:23 INFO - Running setup.py (path:/tmp/pip-OopbgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:23 INFO - Running setup.py (path:/tmp/pip-4qcy_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:23 INFO - Running setup.py (path:/tmp/pip-r54jXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:23 INFO - Running setup.py (path:/tmp/pip-PwnmN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:23 INFO - Running setup.py (path:/tmp/pip-c_zJJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:23 INFO - Running setup.py (path:/tmp/pip-thlrwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:24 INFO - Running setup.py (path:/tmp/pip-oRbUGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:41:24 INFO - Running setup.py install for manifestparser 03:41:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozcrash 03:41:24 INFO - Running setup.py install for mozdebug 03:41:24 INFO - Running setup.py install for mozdevice 03:41:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozfile 03:41:24 INFO - Running setup.py install for mozhttpd 03:41:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozinfo 03:41:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozInstall 03:41:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:41:24 INFO - Running setup.py install for mozleak 03:41:25 INFO - Running setup.py install for mozlog 03:41:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for moznetwork 03:41:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for mozprocess 03:41:25 INFO - Running setup.py install for mozprofile 03:41:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for mozrunner 03:41:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Running setup.py install for mozscreenshot 03:41:25 INFO - Running setup.py install for moztest 03:41:25 INFO - Running setup.py install for mozversion 03:41:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:41:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:41:25 INFO - Cleaning up... 03:41:25 INFO - Return code: 0 03:41:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:25 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 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41:25 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:41:25 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:41:25 INFO - Using env: {'DISPLAY': ':0', 03:41:25 INFO - 'HOME': '/home/cltbld', 03:41:25 INFO - 'LANG': 'en_US.UTF-8', 03:41:25 INFO - 'LANGUAGE': 'en_US:en', 03:41:25 INFO - 'LOGNAME': 'cltbld', 03:41:25 INFO - 'MAIL': '/var/mail/cltbld', 03:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:25 INFO - 'MOZ_NO_REMOTE': '1', 03:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:25 INFO - 'NO_EM_RESTART': '1', 03:41:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:25 INFO - 'PWD': '/builds/slave/test', 03:41:25 INFO - 'SHELL': '/bin/bash', 03:41:25 INFO - 'SHLVL': '1', 03:41:25 INFO - 'TERM': 'linux', 03:41:25 INFO - 'TMOUT': '86400', 03:41:25 INFO - 'USER': 'cltbld', 03:41:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:25 INFO - '_': '/tools/buildbot/bin/python'} 03:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:26 INFO - Running setup.py (path:/tmp/pip-UL8PRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:26 INFO - Running setup.py (path:/tmp/pip-kXgl3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:26 INFO - Running setup.py (path:/tmp/pip-aPCthU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:26 INFO - Running setup.py (path:/tmp/pip-2M9cUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:26 INFO - Running setup.py (path:/tmp/pip-OUaKoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:26 INFO - Running setup.py (path:/tmp/pip-1c9jJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:26 INFO - Running setup.py (path:/tmp/pip-VEqlN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:26 INFO - Running setup.py (path:/tmp/pip-TknmEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:26 INFO - Running setup.py (path:/tmp/pip-NfPyLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:26 INFO - Running setup.py (path:/tmp/pip-9Wl79Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:26 INFO - Running setup.py (path:/tmp/pip-_p3mzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:26 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:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:26 INFO - Running setup.py (path:/tmp/pip-oPOQDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:27 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:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:27 INFO - Running setup.py (path:/tmp/pip-UVr7SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:27 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:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:27 INFO - Running setup.py (path:/tmp/pip-J_pGSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:27 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:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:27 INFO - Running setup.py (path:/tmp/pip-em5Zfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:27 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:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:27 INFO - Running setup.py (path:/tmp/pip-GyeuED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:27 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:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:27 INFO - Running setup.py (path:/tmp/pip-Rbq_WH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:27 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:41:27 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:41:27 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:41:27 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:41:27 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:41:27 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:41:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:29 INFO - Downloading blessings-1.6.tar.gz 03:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:41:29 INFO - Installing collected packages: blessings 03:41:29 INFO - Running setup.py install for blessings 03:41:29 INFO - Successfully installed blessings 03:41:29 INFO - Cleaning up... 03:41:29 INFO - Return code: 0 03:41:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:29 INFO - Reading from file tmpfile_stdout 03:41:29 INFO - Current package versions: 03:41:29 INFO - argparse == 1.2.1 03:41:29 INFO - blessings == 1.6 03:41:29 INFO - blobuploader == 1.2.4 03:41:29 INFO - docopt == 0.6.1 03:41:29 INFO - functools32 == 3.2.3-2 03:41:29 INFO - jsonschema == 2.5.1 03:41:29 INFO - manifestparser == 1.1 03:41:29 INFO - mozInstall == 1.12 03:41:29 INFO - mozcrash == 0.17 03:41:29 INFO - mozdebug == 0.1 03:41:29 INFO - mozdevice == 0.48 03:41:29 INFO - mozfile == 1.2 03:41:29 INFO - mozhttpd == 0.7 03:41:29 INFO - mozinfo == 0.9 03:41:29 INFO - mozleak == 0.1 03:41:29 INFO - mozlog == 3.2 03:41:29 INFO - moznetwork == 0.27 03:41:29 INFO - mozprocess == 0.23 03:41:29 INFO - mozprofile == 0.28 03:41:29 INFO - mozrunner == 6.12 03:41:29 INFO - mozscreenshot == 0.1 03:41:29 INFO - mozsystemmonitor == 0.3 03:41:29 INFO - moztest == 0.7 03:41:29 INFO - mozversion == 1.4 03:41:29 INFO - psutil == 3.1.1 03:41:29 INFO - requests == 1.2.3 03:41:29 INFO - wsgiref == 0.1.2 03:41:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41:29 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:41:29 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:41:29 INFO - Using env: {'DISPLAY': ':0', 03:41:29 INFO - 'HOME': '/home/cltbld', 03:41:29 INFO - 'LANG': 'en_US.UTF-8', 03:41:29 INFO - 'LANGUAGE': 'en_US:en', 03:41:29 INFO - 'LOGNAME': 'cltbld', 03:41:29 INFO - 'MAIL': '/var/mail/cltbld', 03:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:29 INFO - 'MOZ_NO_REMOTE': '1', 03:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:29 INFO - 'NO_EM_RESTART': '1', 03:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:29 INFO - 'PWD': '/builds/slave/test', 03:41:29 INFO - 'SHELL': '/bin/bash', 03:41:29 INFO - 'SHLVL': '1', 03:41:29 INFO - 'TERM': 'linux', 03:41:29 INFO - 'TMOUT': '86400', 03:41:29 INFO - 'USER': 'cltbld', 03:41:29 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:29 INFO - '_': '/tools/buildbot/bin/python'} 03:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:29 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:41:29 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:41:29 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:41:29 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:41:29 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:41:29 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:41:29 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:41:29 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:41:29 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:41:29 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:41:29 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:41:29 INFO - Cleaning up... 03:41:29 INFO - Return code: 0 03:41:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41:29 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:41:29 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:41:29 INFO - Using env: {'DISPLAY': ':0', 03:41:29 INFO - 'HOME': '/home/cltbld', 03:41:29 INFO - 'LANG': 'en_US.UTF-8', 03:41:29 INFO - 'LANGUAGE': 'en_US:en', 03:41:29 INFO - 'LOGNAME': 'cltbld', 03:41:29 INFO - 'MAIL': '/var/mail/cltbld', 03:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:29 INFO - 'MOZ_NO_REMOTE': '1', 03:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:29 INFO - 'NO_EM_RESTART': '1', 03:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:29 INFO - 'PWD': '/builds/slave/test', 03:41:29 INFO - 'SHELL': '/bin/bash', 03:41:29 INFO - 'SHLVL': '1', 03:41:29 INFO - 'TERM': 'linux', 03:41:29 INFO - 'TMOUT': '86400', 03:41:29 INFO - 'USER': 'cltbld', 03:41:29 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:29 INFO - '_': '/tools/buildbot/bin/python'} 03:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:41:30 INFO - Cleaning up... 03:41:30 INFO - Return code: 0 03:41:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:41: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:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf15dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a99350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, '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': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', '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:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:41:30 INFO - Using env: {'DISPLAY': ':0', 03:41:30 INFO - 'HOME': '/home/cltbld', 03:41:30 INFO - 'LANG': 'en_US.UTF-8', 03:41:30 INFO - 'LANGUAGE': 'en_US:en', 03:41:30 INFO - 'LOGNAME': 'cltbld', 03:41:30 INFO - 'MAIL': '/var/mail/cltbld', 03:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:30 INFO - 'MOZ_NO_REMOTE': '1', 03:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:30 INFO - 'NO_EM_RESTART': '1', 03:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:30 INFO - 'PWD': '/builds/slave/test', 03:41:30 INFO - 'SHELL': '/bin/bash', 03:41:30 INFO - 'SHLVL': '1', 03:41:30 INFO - 'TERM': 'linux', 03:41:30 INFO - 'TMOUT': '86400', 03:41:30 INFO - 'USER': 'cltbld', 03:41:30 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:30 INFO - '_': '/tools/buildbot/bin/python'} 03:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:30 INFO - Downloading/unpacking flake8 03:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:41:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:41:32 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:41:32 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:41:32 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:41:32 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:41:32 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:41:32 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:41:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:41:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:41:32 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:41:32 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:41:32 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:41:32 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:41:32 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:41:32 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:41:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:41:32 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:41:32 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:41:32 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:41:32 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:41:32 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:41:32 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:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:41:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:41:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:41:32 INFO - no previously-included directories found matching 'docs/_build' 03:41:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:41:33 INFO - Running setup.py install for pep8 03:41:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:41:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:41:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:41:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:41:33 INFO - no previously-included directories found matching 'docs/_build' 03:41:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:41:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:41:33 INFO - Cleaning up... 03:41:33 INFO - Return code: 0 03:41:33 INFO - Running post-action listener: _resource_record_post_action 03:41:33 INFO - Running post-action listener: _start_resource_monitoring 03:41:33 INFO - Starting resource monitoring. 03:41:33 INFO - ##### 03:41:33 INFO - ##### Finished create-virtualenv step (success) 03:41:33 INFO - ##### 03:41:33 INFO - ##### 03:41:33 INFO - ##### Running install step. 03:41:33 INFO - ##### 03:41:33 INFO - Running pre-action listener: _resource_record_pre_action 03:41:33 INFO - Running main action method: install 03:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:33 INFO - Reading from file tmpfile_stdout 03:41:33 INFO - Detecting whether we're running mozinstall >=1.0... 03:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:41:33 INFO - Reading from file tmpfile_stdout 03:41:33 INFO - Output received: 03:41:33 INFO - Usage: mozinstall [options] installer 03:41:33 INFO - Options: 03:41:33 INFO - -h, --help show this help message and exit 03:41:33 INFO - -d DEST, --destination=DEST 03:41:33 INFO - Directory to install application into. [default: 03:41:33 INFO - "/builds/slave/test"] 03:41:33 INFO - --app=APP Application being installed. [default: firefox] 03:41:33 INFO - mkdir: /builds/slave/test/build/application 03:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:41:49 INFO - Reading from file tmpfile_stdout 03:41:49 INFO - Output received: 03:41:49 INFO - /builds/slave/test/build/application/firefox/firefox 03:41:49 INFO - Running post-action listener: _resource_record_post_action 03:41:49 INFO - ##### 03:41:49 INFO - ##### Finished install step (success) 03:41:49 INFO - ##### 03:41:49 INFO - ##### 03:41:49 INFO - ##### Running run-tests step. 03:41:49 INFO - ##### 03:41:49 INFO - Running pre-action listener: _resource_record_pre_action 03:41:49 INFO - Running main action method: run_tests 03:41:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:41:49 INFO - Python 2.7.3 03:41:49 INFO - Return code: 0 03:41:49 INFO - grabbing minidump binary from tooltool 03:41:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:49 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 0x2b281c0>, '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 0x2b290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b29550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:41:49 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:41:49 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:41:49 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:41:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:41:49 INFO - Return code: 0 03:41:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:41:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:41:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:41:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:41:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:41:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:41:50 INFO - Return code: 0 03:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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:41:50 INFO - Using env: {'DISPLAY': ':0', 03:41:50 INFO - 'HOME': '/home/cltbld', 03:41:50 INFO - 'LANG': 'en_US.UTF-8', 03:41:50 INFO - 'LANGUAGE': 'en_US:en', 03:41:50 INFO - 'LOGNAME': 'cltbld', 03:41:50 INFO - 'MAIL': '/var/mail/cltbld', 03:41:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:41:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:50 INFO - 'MOZ_NO_REMOTE': '1', 03:41:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:41:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:50 INFO - 'NO_EM_RESTART': '1', 03:41:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:50 INFO - 'PWD': '/builds/slave/test', 03:41:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:41:50 INFO - 'SHELL': '/bin/bash', 03:41:50 INFO - 'SHLVL': '1', 03:41:50 INFO - 'TERM': 'linux', 03:41:50 INFO - 'TMOUT': '86400', 03:41:50 INFO - 'USER': 'cltbld', 03:41:50 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151', 03:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:50 INFO - '_': '/tools/buildbot/bin/python'} 03:41:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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:41:50 INFO - mozversion application_buildid: 20160731234823 03:41:50 INFO - mozversion application_changeset: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff 03:41:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:41:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:41:50 INFO - mozversion application_name: Firefox 03:41:50 INFO - mozversion application_remotingname: firefox-dev 03:41:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:50 INFO - mozversion application_vendor: Mozilla 03:41:50 INFO - mozversion application_version: 49.0a2 03:41:50 INFO - mozversion platform_buildid: 20160731234823 03:41:50 INFO - mozversion platform_changeset: fcdf4bb703567bca5a5d7065f3a8a35ce1dea9ff 03:41:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:50 INFO - mozversion platform_version: 49.0a2 03:41:50 INFO - using testdate: 1470048110 03:41:50 INFO - actual date: 1470048110 03:41:50 INFO - starting webserver on 'localhost:44499' 03:41:50 INFO - SUITE-START | Running 2 tests 03:41:50 INFO - TEST-START | dromaeo_css 03:41:50 INFO - operating with platform_type : linux_ 03:41:50 INFO - Initialising browser for dromaeo_css test... 03:41:50 INFO - TEST-INFO | started process 24817 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD073K9/profile http://localhost:44499/getInfo.html) 03:41:53 INFO - PROCESS | 24817 | __metrics Screen width/height:1600/1200 03:41:53 INFO - PROCESS | 24817 | colorDepth:24 03:41:53 INFO - PROCESS | 24817 | Browser inner width/height: 1024/697 03:41:53 INFO - PROCESS | 24817 | __metrics 03:41:54 INFO - PROCESS | 24817 | 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:41:54 ERROR - PROCESS | 24817 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:41:55 INFO - TEST-INFO | 24817: exit 0 03:41:55 INFO - Browser initialized. 03:41:55 INFO - Running cycle 1/1 for dromaeo_css test... 03:41:55 INFO - TEST-INFO | started process 24910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD073K9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:07 INFO - PROCESS | 24910 | RSS: Main: 143060992 03:42:07 INFO - PROCESS | 24910 | 03:42:58 INFO - PROCESS | 24910 | 1470048178898 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:45:06 INFO - PROCESS | 24910 | Cycle 1(1): loaded http://localhost:44499/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44499/tests/dromaeo/cssquery-ext.html) 03:45:07 INFO - PROCESS | 24910 | RSS: Main: 255512576 03:45:07 INFO - PROCESS | 24910 | 03:48:10 INFO - PROCESS | 24910 | Cycle 1(1): loaded http://localhost:44499/tests/dromaeo/cssquery-ext.html (next: http://localhost:44499/tests/dromaeo/cssquery-jquery.html) 03:48:10 INFO - PROCESS | 24910 | RSS: Main: 231096320 03:48:10 INFO - PROCESS | 24910 | 03:51:12 INFO - PROCESS | 24910 | Cycle 1(1): loaded http://localhost:44499/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44499/tests/dromaeo/cssquery-mootools.html) 03:51:13 INFO - PROCESS | 24910 | RSS: Main: 219639808 03:51:13 INFO - PROCESS | 24910 | 03:54:15 INFO - PROCESS | 24910 | Cycle 1(1): loaded http://localhost:44499/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44499/tests/dromaeo/cssquery-prototype.html) 03:54:15 INFO - PROCESS | 24910 | RSS: Main: 218112000 03:54:15 INFO - PROCESS | 24910 | 03:57:18 INFO - PROCESS | 24910 | Cycle 1(1): loaded http://localhost:44499/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44499/tests/dromaeo/cssquery-yui.html) 03:57:19 INFO - PROCESS | 24910 | RSS: Main: 216145920 03:57:19 INFO - PROCESS | 24910 | 04:00:21 INFO - PROCESS | 24910 | Cycle 1(1): loaded http://localhost:44499/tests/dromaeo/cssquery-yui.html (next: http://localhost:44499/tests/dromaeo/cssquery-dojo.html) 04:00:22 INFO - PROCESS | 24910 | RSS: Main: 232480768 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | __start_tp_report 04:00:22 INFO - PROCESS | 24910 | _x_x_mozilla_page_load 04:00:22 INFO - PROCESS | 24910 | _x_x_mozilla_page_load_details 04:00:22 INFO - PROCESS | 24910 | |i|pagename|runs| 04:00:22 INFO - PROCESS | 24910 | |0;dojo.html;3391.1522119470133;3407.0460271124084;3420.5382273393093;3451.2579795343995;3456.222349971257;5595.3285605727315;5701.2018317435595;5709.371969083398;5710.914336284954;5721.771129154834;5658.207850900877;5728.656280623166;5731.484166425032;5735.1970724098655;5741.339745929219;4962.206047032476;5071.594272458213;5143.331366922304;5192.247124166994;5213.374395072597;5132.871678208037;5180.430152683201;5183.688978661274;5184.066867963765;5185.040767458014;5068.949310506903;5089.236614507817;5109.591232701375;5123.2315152727015;5178.6892786432745;5163.664361816489;5179.404368497633;5186.377634683843;5190.792368305301;5219.4780521947505;5014.598832093424;5027.396712394499;5059.342285502861;5088.440271570124;5100.591952643779;5402.054640437907;5406.810761623325;5412.431694672042;5424.267723857268;5476.288082549283;6058.36940693011;6076;6081.27024757032;6090.512758979271;6099.939000609981;5948.702564871739;5996.16053752475;6003.27960644721;6074;6074.332751309554;5636.971815140939;5684.914726279109;5689;5694.288213973212;5697.4302569742695;6080.209572755513;6156.445919867234;6167.259928808572;6171.84570385744;6175.722092505848;5896.469317761336;5926.496247818984;5936.792212272636;5986.2517185351835;6025.427584379477;6107.8473038173515;6109.083637454418;6109.969450152721;6110.419510146529;6111.816645500642;5895.882082358329;5937.050071988461;6054.212952316171;6056.364081771439;6057.454829065406;6004.54965877561;6067.423594758491;6067.423594758491;6080.665563394055;6115.816525504242;4614.123316569841;4627.051454451829;4632.652551058684;4632.884177895579;4652.860414187579;4019.1157945252;4023.959760402417;4038.0106873815516;4115.835366585303;4134.896627584334;5684.374718781013;5706.71466426677;5709.115087161496;5712.257406537206;5736.4550367715;5902.1441890926435;5959.642421454727;5960.37416071315;5961.195238642815;5982.880342393215;5667.688277144796;5726.656400615976;5728.513076388554;5768.74040668171;5792.913106303409;5176.145935920531;5182.559482444034;5183.377994640592;5190.11767999441;5195.038917800219;6436.388543088399;6442.130312407765;6449.161608990801;6450.709718062604;6453.612783233021;5953.1070339449425;5965;6026.035834266497;6040.969795150996;6099.3900609938655;6344.619322840645;6387;6397.840053998501;6398.5201109916015;6405.807825765234;5689;5934.406559344031;5942.257217847769;5951.642901425928;5951.880962380642;5921.703914804157;5949.791757288474;5985;6039.335673076046;6044.90932636002;5861.531077513874;5900.793472228452;5905.734241959036;5908.497777688945;5911.674857882857;5797.101449275369;5818.6217895836635;5822.854428639318;5867.970660146672;5876.706164691663;5761.971190144022;5838.12428135783;5842.591018628656;5847.210626565529;5883;5936.257967754031;5950.910736339045;5983.670898100646;5992.250968628921;5993.0710739835395;5698.800541981011;5768.134779783066;5812.447817457335;5839.357670656309;5855.882882342244 04:00:22 INFO - PROCESS | 24910 | |1;ext.html;608.604407135362;640.4748106552627;653.2879161713731;663.1875951958848;671.4326394196905;32472.512912306305;44956.77521612391;46370.76814615931;46405.767971160094;46437.30344044842;48241.51758482414;48595.75702121484;48730;48755.51244487554;48893.51106488934;8240.958795206045;9041.3671043027;9175.44947303164;9193.678221262257;10673.466326683674;10504.947475262614;10935.781284374309;10955.726106847313;10959.178061645369;10965.890341096607;10961.671149865517;11003.22977391583;11007.174461915349;11013.394263315515;11013.724656883562;8350;10899.673009809718;10905.073068789163;11145.832812507819;11149.72125696856;10577.894221057844;10993.725156871062;10997.34015959045;11001.339919604849;11010.779784404347;46252.53747462516;46473.53526464726;46599;46804.5319546807;46994.29508557374;3662.322470342995;3920.1375697346825;4093.6314633659294;4221.493420789494;4463.352813842007;35408.46887296693;35576.64423355759;35888.28223435543;35959.82020089909;37116.25767484662;4680.157571637104;4711.246200607886;4719.858404247878;4723.173444647173;4868.513148685139;410030;412304.999999997;412391;412512;412801;35616.643833561335;35992.28015439729;70769.64615176994;71221;71712.64143679249;42141.57858421438;42208.3668744969;42229.57770422318;42289.57710422918;42391.15217695691;17523.29906803739;17647;17673;17711.114444277733;17748.91125544364;13201.73596528064;14012.439502419993;14443.638909027359;14474;14801.703965920837;9401.529923503935;9430.387024843363;9436.905630943602;9484;9507.952460237655;3543.114221444639;3605.0446631642635;3610.4042832932796;3644.9817750911075;3675.22820207754;1081.8269076947652;1089.9019088282093;1103.674416047268;1163.6218229075585;1167.4396289780952;34289.31421371559;34508.482372764425;34869.476957845654;35106.47340289898;35593.64406355955;1128.4132251229314;1179.351356753782;1179.5694571481347;1215.094754407954;1245.6705959855128;16332.346706131859;17059.829401705825;17089;17209.483715488554;17623.032675561226;594607;608577;608701;609217;611118;62425.68787156126;63342.36657633457;63472.36527634757;63626;63696;1398.1121987537986;1492.6865358274667;1510.3807438949977;1524.9771253431206;1556.4785796757985;34425.311493769485;35391.292174156886;35534.644653553136;35813.28373432569;35912.46131308085;56177;57244.42755572391;57321.71339143278;57355.139672905774;57412.71293643672;488938;501930;501997;502007;502482;39119.608803912735;45397.54602454065;46066;46278.537214627424;50402.7479862613;51678.741606291725;52822.73588632032;53747.19379209393;53878.461215387346;54708.179377308574;44191.52664877911;44418;44835;44933.55066449424;46615.30077048779;42740;43355.78322108369;43425.17736347254;43622.56377436312;44194;44366.3345049831;44443;44584;44830.55169448394;44867.10265794601;37249.25501490009;40064.59773907899;40076.1984760309;40154;40705;30061.496925153217;30191.547126792677;30554.541681875235;30582.694173057986;30587.388252235276 04:00:22 INFO - PROCESS | 24910 | |2;jquery.html;433.1964206397132;433.8437963322289;435.39914917413967;438.9714668546544;439.8526493624636;18664.813351866476;19702.605947881053;20238.29165979191;21012.579748405024;21044.158233670634;19808.108635111417;20142.093605787737;20440.591188176226;20465.897670511666;20553.794462055375;5640.633358831673;5830.387809280028;5862.88274234515;5885.264341957255;5887.793927212549;5826.21346118275;5831.212786273863;5892.734826932777;5915.438033386821;5923.526117910556;5808.680522571257;5849.181114643953;5852.444017818321;5860.619059761123;5867.442592953663;5661.49970257882;5840.211571437865;5866.70666466675;5868.76524939002;5880.323762767293;5179.741012949337;5226.137687281594;5283.6037297202865;5284.867878303035;5287.391949925769;23726.05095796166;23971.52056958851;24042.7595724044;24359.878200609062;24525.877370613212;2501;2513.5726926422553;2630.8947642094295;2758.9586156207674;3155.652878183398;13803.447862085504;14350.13899166043;14506.41974321026;15136.848631513654;15373.308201130976;1769.4956937272943;1891.1206289075647;2001.6997450382414;2030.3807338761671;2057;64669.353306467274;69434.65282673606;79500.60249698772;80181.59909200417;87613.56193218993;11013.058255321743;11828.881711182778;11947.223430476992;12045.457954392074;12110.333931633668;16958.576035598962;17105.14474276281;17117.400890969006;17157.399491017753;17157.485275441755;7022.54353467023;7044.788656340318;8637.784055398539;8767.123287671182;8968.775780605407;8701;8716.91283087161;8752.387332886638;8860.601272942733;8864.955675221712;5064.721440320818;5106.208537676666;5117.948820511821;5149.9742501288;5209.374875014947;2011.939641810748;2071.564971356032;2082.37528741377;2096.15105882118;2144.3459744777992;962.2686758063782;1065.4566171252718;1067.114295135036;1079.8056349857102;1091.1543553745753;19190.232390704496;19219.711704324447;19363.90318048401;19459.318923837596;19537.90231048864;1000.0899181744578;1010.5907107621425;1021.7037059252733;1035.699647102347;1072.8498010278568;10682.946585267024;10790.136789056856;10911.672649820519;11514.712132196762;11753.823692644617;398599;404684;406860;595178;613640;73042.26957730461;75713;95221;108489.45755271331;108888.45555772171;1038.7558923652934;1066.402814423925;1087.690008347617;1089.2484185911537;1092.7377429416883;17683.38108166208;17865.28538858483;17905.37331193402;17963.820361796214;18187.909060455142;42788.78605606952;45069.774651126536;45391;46389.304160438296;48131;51922.740386297824;56774;57777.422225778886;64267;65269;13047.934760326138;13404.865951340362;13538.12002219892;13542.322883855571;13649.317534123056;14556.053856499717;14571.41714331415;14657.853421465648;14719.411223550938;14787.852121478647;12775.61673149807;12921.095523313277;12922.806157907451;13095.541656042375;13100.803487947878;12248.414908082343;12589.685257868261;12779.936100319439;12817.615471535495;13027.609171724864;12707.87292127067;12864;12928.288944107788;13132.212067275994;13298.268595227355;13352.532661356807;13384.263865487492;13384.531541396005;13429.194248345131;13443.395047222702;12729.108962372546;12830.743385132431;12850.485980560672;12953.158044727063;13014.544490942772 04:00:22 INFO - PROCESS | 24910 | |3;mootools.html;1408.8943329250308;1592.3232626133895;1618.8057433108024;1821.6994195957668;1926.2198809482165;8540.957295213522;9524.095210954956;9722.465264410463;9731.562079706413;10041.297109202358;9659.082387173225;9801.558929848155;9826.950865245664;9837.60649574018;9840.40957542548;2126.617208902397;2457.213691618683;2516.446381796002;2522.5585522533574;2567.358160459885;2547.4395632960814;2566.3455818766283;2574;2577.072253988572;2577.85821779802;2532.670752802133;2577.8324408913454;2579.88390522426;2581.186926118276;2583.922482325524;2081.844576260175;2410.1203060882654;2470.6170543565777;2479.00839664134;2484.1181380609805;2535.315464824487;2538.530371881208;2562.9615555766677;2564.064116597447;2592.2223333000024;9765.462899540522;9872.062154095349;9907.504624768733;9914.454704991223;9919.50402479873;5645;5783.161441590988;5795.681237531934;5800.419958004208;5802.12968054791;3613.439916812898;3672.173760903802;3750.7374483786148;3845.3270677631303;3847.5190601174854;1142.765733024728;1164.9009834164106;1197.6586672798298;1206.8551773787115;1258.0690289185936;14171.503997360043;14175;14179.220142892136;14237;14267.785983210157;5202.453742357074;5206.895862082737;5270.130428479258;5296.152615581565;5302.549283310962;11772.470238839272;11856.22934509254;11857.051435885109;11862.406879656155;11863.228890122115;5923;5924.348321684612;5925.259342582177;5935.198748168942;5937.70311484424;4676.462206846256;5228.424873263938;5354.464553544614;5358.571314294847;5429;4328.935065974013;4367.45406824147;4394.956050439519;4432.756198409054;4438.489573699001;1382.219046238872;1394.8884089272833;1400.0829456705874;1401.1663060479;1426.2084543078483;924.1405492891541;1030.6907927621683;1045.2578669144991;1062.7130674717782;1086.6088208245026;6411.647359395185;6414.711337989802;6518.022296655445;6594.802155935329;6670.466362690973;479.72655586315466;497.3360563647499;509.19292920720335;512.8846009647874;513.8612574604836;4511.977440112844;4561.703489273186;4580.450345958439;4582.221022426195;4585.862424127282;11916.344601046852;11939.88060119405;12253.571125010581;12365.258084514779;12416.937915310365;12443.751124977449;12716.173448725804;12770.042246831339;12805.807912881315;12816.166949148275;9052.728418147466;9259.95370023159;9270.212031977222;9278.489683067495;9285.164335209864;6706.362895525112;6828.692708828214;6875.037494750639;6987.336203060701;7014.719411223698;11001.22991390595;11004.669859904216;11312.26470279462;11544.480498377761;11610.94194529022;11260.662180134608;11476.221185716227;11604.88395116038;11641.243319184226;11732.296062236293;7889;8477.660893564434;8685.565721713763;8708.216260536583;8915.465072095436;9535.904640953779;9568.760780980252;9588.66439674608;9597.28020398459;9627.90372096298;8410.831783364421;8573.356998225034;8612.224899758801;8680.78298042554;8740.03859575459;8320.50076995358;8354.832903341778;8379.120192379713;8380;8699.652013919373;7629.504082234637;7630.084389873251;7699.461037727306;7753.340966017726;7797.7660670179985;5288.365396152486;5553.8333849984565;5558.555315574825;5667.40492248308;5707.315122185199;5561.555075594024;5611.270534830609;5646.0683987141665;5648.096304591246;5667.546596272371 04:00:22 INFO - PROCESS | 24910 | |4;prototype.html;343.6717934372674;352.1987478486443;355.49164694486876;357.64235764235764;359.29578027066947;5404.270423492827;5450.400455949847;5523.7790488380415;5547.169434086752;5560.332760068793;5364.861555989741;5410.945890541094;5449.329828384093;5485.9725701371435;5489.972550137244;3825.48355971943;4029.7783621900858;4262.318029115342;4267;4287.871363859089;4182.733611716048;4211.43145675333;4215.409842622035;4227.704060715751;4227.894302642428;4049;4192.24539582875;4233.153369326147;4235.8729238122905;4245.384419259206;4085.385353373843;4193.874183774492;4197.874063778091;4211.052513184509;4229.534751177368;3673.9632603674154;3705.1663375740513;3728.4780130781714;3730.2539492101687;3777.5466943966626;5244.475552444763;5273.894522109536;5274.709890955995;5283.603729720248;5304.20436934459;4867.906800075966;4886.169351210303;4919.877003074916;4930.087933732271;4934.358533390672;5422.810201642963;5540.168974653794;5571.331440227158;5599.188117722907;5734.340550836666;4015.819288132012;4020;4084.7344922579937;4114.6091121343725;4120.340745480738;5810.941890581083;5850.180974663551;5859.296884373903;5873.765049398062;5876.706164691791;4149.568398902374;4299.570042995738;4311.137772445523;4337.457817772778;4339.327404252283;4334.696571240046;4360.302351623725;4396.142752163361;4402.669799765031;4403.383526306319;4951.90096198074;4972.453030166679;4977.726225057657;4986.601071914238;5000.149974504343;5478.835634930959;5702.8289151325525;5740.225069615632;5755;5780.971095144581;1503.9398424063133;1513.1526345246698;1515.2348064227494;1515.9691409841414;1531.5941275561986;953.0707560128819;953.9618415263312;968.3076600230869;990.2474119669104;990.4700984973005;5015.445211984296;5037.823676171316;5057.671251368649;5060.240963855377;5073.15903094261;5219.6950762309425;5262.578993680497;5273.367195936512;5280.81517146898;5285.312909321764;5048.72232027242;5073.289739436482;5087.389513258358;5269.841904742864;5708;3122.266267427153;3128.499440089575;3133.2323580722414;3137;3156.510740835174;6202.193714817045;6204.689765511706;6211.9068213976825;6213.596116252429;6223.719932602953;5736.942630573641;6033.3664965178905;6039.78860739872;6040.338906800665;6049.5462840287155;5881.5588830837005;6038.275406951194;6191.152070047829;6217.813465596039;6226.6575338355915;5825.475707186373;5850.327212370632;5852.882942341044;5875.20684707559;5930.673812940157;5666.886662266814;5816.738246778989;5863.20846685692;5877.500412464818;5882.646891271934;6115.3578874217565;6121.112438696453;6131.478824299985;6135.9386406137155;6233.407826256498;4194.580541945781;4218.198542276958;4227.492700875914;4236.195122926634;4245.787710614395;5736.340320863154;5857.853553661193;5937.168796368425;5940.851478713067;5943.554233432596;5795.33353664334;5808.419158084327;5808.5062769665055;5811.012127938345;5830;5521.254630624814;5776.19133321327;5790.449907258803;5801.912971305349;5812.651240925389;5605.5515558756015;5621.775128994795;5637.46444087811;5650.180723794944;5656.971715141398;5797.884042319214;5803.7968671096305;5808.186853840551;5821.137236084582;5828.125781132864;5490.6980116094;5667.2632557768875;5677.1768093627015;5709.51469125129;5718 04:00:22 INFO - PROCESS | 24910 | |5;yui.html;707.812429706128;737.8819388897774;806.3065763443434;836.6026137584647;848.537547036865;15043;15092.622684432894;15093.396264149435;15126.924365378158;15267.389304427823;15437.45968891089;15835.920820395912;15838.84161158388;15854.365825366971;15873.365065397398;713.2546488919093;830.779843341515;843.9282660973824;853.108501615813;857.5069335132292;835.8871552340447;839.6053854688287;842.3471809347774;844.2359664503634;844.4215712237137;896.3725392225468;898.7528429681824;898.7753061734567;898.9190972812446;898.993127696977;743.305009815824;874.2961915657893;894.4901406198469;895.0288936503902;897.1880448194395;660.6498555765452;661.6460193796296;662.1557514169424;662.3674390956625;662.572640646785;14681.412743490248;14714.264286785618;14721.55835324942;14725.852741472663;14739.041962272418;13432.731345373038;13541;13619.319034048258;13672;13814.65463363414;12024.141593623046;12267.938660306641;12313.93843030788;12429.564965226264;12447.564335248313;4444.177827101964;4570.405847239837;4599.517050709695;4612.607928326096;4647.86056418308;77229;77389.61305193494;77712.61143694358;77942;78151;11335.773284534429;11442.542298307975;11630.88369116315;11731.88268117325;11788.23376480526;19515.51211219731;19589;19590.510237244183;19609.411717648494;19639.705404418946;8435;8570.442921210102;8615.6984505542;8894.199522043047;8948.373613846969;4842.346283251716;4890.486498917634;4916.557509824133;4992.301077849104;5022.070916880389;3537.451694987281;3665.6334366563665;3670.541182352206;3671.192337685705;3671.394219953731;654.3979538824293;660.6366498425847;663.3697986912356;674.5547938360756;676.6549059979446;680.2755065854849;703.9788806335819;706.3471476744443;717.4475653746586;746.73117677636;10034.899651003543;10060.74848128788;10103.14123299513;10154;10257.897421025844;656.0159760359461;684.2439104789236;691.4192078653955;739.8335374540793;766.3332900376708;4713.198756211452;4732.810687572527;4740.834070807505;4881.536254055859;4935.679180853233;106056;109438;112707;118271;121685;98951;102098.48950755199;102265.48867255617;102315.4884225574;102652.48673756584;59951;71511.6424417885;90650.09349906548;90908.54545727362;91488.54255728678;9162.67930622425;9176.402682960937;9320.487373194539;9362;9515.096065873731;53269.733651331495;54677.45322546724;55101.72449137729;55256.44743552513;55490.72254638701;67556.66221668861;67749.32250677627;68520.31479685339;77111.61444192744;83214.58392708239;13857.168569885838;13965.3715582797;14030.228337441538;14245.145291282557;14320.14079155254;12189;14509.347079381241;15483.922580387027;15768.526944191693;15824.92087539601;13926.791098133333;14114.36485358182;15022.323995420014;15511.612209694847;15964.680706385576;13463.461461541428;14335.56993290203;14478.131312121306;14556.563303100924;15477;14300.499482518062;14527.636809079857;14740.926295368883;14743.852561474248;15117.773233401727;10789.352638841694;11254.493547790205;11827.290362578273;11992.64022079339;12134.75730485403;12167.087468440079;12387.75224495487;12529.934955528883;12784.87215127874;12904.419301131284 04:00:22 INFO - PROCESS | 24910 | __end_tp_report 04:00:22 INFO - PROCESS | 24910 | __start_cc_report 04:00:22 INFO - PROCESS | 24910 | _x_x_mozilla_cycle_collect,897 04:00:22 INFO - PROCESS | 24910 | __end_cc_report 04:00:22 INFO - PROCESS | 24910 | __startTimestamp1470049222043__endTimestamp 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | ------- Summary: start ------- 04:00:22 INFO - PROCESS | 24910 | Number of tests: 6 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | [#0] dojo.html Cycles:165 Average:5615.75 Median:5822.85 stddev:633.55 (10.9%) stddev-sans-first:610.98 04:00:22 INFO - PROCESS | 24910 | Values: 3391.2 3407.0 3420.5 3451.3 3456.2 5595.3 5701.2 5709.4 5710.9 5721.8 5658.2 5728.7 5731.5 5735.2 5741.3 4962.2 5071.6 5143.3 5192.2 5213.4 5132.9 5180.4 5183.7 5184.1 5185.0 5068.9 5089.2 5109.6 5123.2 5178.7 5163.7 5179.4 5186.4 5190.8 5219.5 5014.6 5027.4 5059.3 5088.4 5100.6 5402.1 5406.8 5412.4 5424.3 5476.3 6058.4 6076.0 6081.3 6090.5 6099.9 5948.7 5996.2 6003.3 6074.0 6074.3 5637.0 5684.9 5689.0 5694.3 5697.4 6080.2 6156.4 6167.3 6171.8 6175.7 5896.5 5926.5 5936.8 5986.3 6025.4 6107.8 6109.1 6110.0 6110.4 6111.8 5895.9 5937.1 6054.2 6056.4 6057.5 6004.5 6067.4 6067.4 6080.7 6115.8 4614.1 4627.1 4632.7 4632.9 4652.9 4019.1 4024.0 4038.0 4115.8 4134.9 5684.4 5706.7 5709.1 5712.3 5736.5 5902.1 5959.6 5960.4 5961.2 5982.9 5667.7 5726.7 5728.5 5768.7 5792.9 5176.1 5182.6 5183.4 5190.1 5195.0 6436.4 6442.1 6449.2 6450.7 6453.6 5953.1 5965.0 6026.0 6041.0 6099.4 6344.6 6387.0 6397.8 6398.5 6405.8 5689.0 5934.4 5942.3 5951.6 5951.9 5921.7 5949.8 5985.0 6039.3 6044.9 5861.5 5900.8 5905.7 5908.5 5911.7 5797.1 5818.6 5822.9 5868.0 5876.7 5762.0 5838.1 5842.6 5847.2 5883.0 5936.3 5950.9 5983.7 5992.3 5993.1 5698.8 5768.1 5812.4 5839.4 5855.9 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | [#1] ext.html Cycles:180 Average:67007.16 Median:412656.50 stddev:136095.54 (33.0%) stddev-sans-first:136386.02 04:00:22 INFO - PROCESS | 24910 | Values: 608.6 640.5 653.3 663.2 671.4 32472.5 44956.8 46370.8 46405.8 46437.3 48241.5 48595.8 48730.0 48755.5 48893.5 8241.0 9041.4 9175.4 9193.7 10673.5 10504.9 10935.8 10955.7 10959.2 10965.9 10961.7 11003.2 11007.2 11013.4 11013.7 8350.0 10899.7 10905.1 11145.8 11149.7 10577.9 10993.7 10997.3 11001.3 11010.8 46252.5 46473.5 46599.0 46804.5 46994.3 3662.3 3920.1 4093.6 4221.5 4463.4 35408.5 35576.6 35888.3 35959.8 37116.3 4680.2 4711.2 4719.9 4723.2 4868.5 410030.0 412305.0 412391.0 412512.0 412801.0 35616.6 35992.3 70769.6 71221.0 71712.6 42141.6 42208.4 42229.6 42289.6 42391.2 17523.3 17647.0 17673.0 17711.1 17748.9 13201.7 14012.4 14443.6 14474.0 14801.7 9401.5 9430.4 9436.9 9484.0 9508.0 3543.1 3605.0 3610.4 3645.0 3675.2 1081.8 1089.9 1103.7 1163.6 1167.4 34289.3 34508.5 34869.5 35106.5 35593.6 1128.4 1179.4 1179.6 1215.1 1245.7 16332.3 17059.8 17089.0 17209.5 17623.0 594607.0 608577.0 608701.0 609217.0 611118.0 62425.7 63342.4 63472.4 63626.0 63696.0 1398.1 1492.7 1510.4 1525.0 1556.5 34425.3 35391.3 35534.6 35813.3 35912.5 56177.0 57244.4 57321.7 57355.1 57412.7 488938.0 501930.0 501997.0 502007.0 502482.0 39119.6 45397.5 46066.0 46278.5 50402.7 51678.7 52822.7 53747.2 53878.5 54708.2 44191.5 44418.0 44835.0 44933.6 46615.3 42740.0 43355.8 43425.2 43622.6 44194.0 44366.3 44443.0 44584.0 44830.6 44867.1 37249.3 40064.6 40076.2 40154.0 40705.0 30061.5 30191.5 30554.5 30582.7 30587.4 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | [#2] jquery.html Cycles:180 Average:29653.72 Median:11235.49 stddev:81336.90 (723.9%) stddev-sans-first:81535.47 04:00:22 INFO - PROCESS | 24910 | Values: 433.2 433.8 435.4 439.0 439.9 18664.8 19702.6 20238.3 21012.6 21044.2 19808.1 20142.1 20440.6 20465.9 20553.8 5640.6 5830.4 5862.9 5885.3 5887.8 5826.2 5831.2 5892.7 5915.4 5923.5 5808.7 5849.2 5852.4 5860.6 5867.4 5661.5 5840.2 5866.7 5868.8 5880.3 5179.7 5226.1 5283.6 5284.9 5287.4 23726.1 23971.5 24042.8 24359.9 24525.9 2501.0 2513.6 2630.9 2759.0 3155.7 13803.4 14350.1 14506.4 15136.8 15373.3 1769.5 1891.1 2001.7 2030.4 2057.0 64669.4 69434.7 79500.6 80181.6 87613.6 11013.1 11828.9 11947.2 12045.5 12110.3 16958.6 17105.1 17117.4 17157.4 17157.5 7022.5 7044.8 8637.8 8767.1 8968.8 8701.0 8716.9 8752.4 8860.6 8865.0 5064.7 5106.2 5117.9 5150.0 5209.4 2011.9 2071.6 2082.4 2096.2 2144.3 962.3 1065.5 1067.1 1079.8 1091.2 19190.2 19219.7 19363.9 19459.3 19537.9 1000.1 1010.6 1021.7 1035.7 1072.8 10682.9 10790.1 10911.7 11514.7 11753.8 398599.0 404684.0 406860.0 595178.0 613640.0 73042.3 75713.0 95221.0 108489.5 108888.5 1038.8 1066.4 1087.7 1089.2 1092.7 17683.4 17865.3 17905.4 17963.8 18187.9 42788.8 45069.8 45391.0 46389.3 48131.0 51922.7 56774.0 57777.4 64267.0 65269.0 13047.9 13404.9 13538.1 13542.3 13649.3 14556.1 14571.4 14657.9 14719.4 14787.9 12775.6 12921.1 12922.8 13095.5 13100.8 12248.4 12589.7 12779.9 12817.6 13027.6 12707.9 12864.0 12928.3 13132.2 13298.3 13352.5 13384.3 13384.5 13429.2 13443.4 12729.1 12830.7 12850.5 12953.2 13014.5 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | [#3] mootools.html Cycles:180 Average:6466.87 Median:2578.09 stddev:3792.05 (147.1%) stddev-sans-first:3783.64 04:00:22 INFO - PROCESS | 24910 | Values: 1408.9 1592.3 1618.8 1821.7 1926.2 8541.0 9524.1 9722.5 9731.6 10041.3 9659.1 9801.6 9827.0 9837.6 9840.4 2126.6 2457.2 2516.4 2522.6 2567.4 2547.4 2566.3 2574.0 2577.1 2577.9 2532.7 2577.8 2579.9 2581.2 2583.9 2081.8 2410.1 2470.6 2479.0 2484.1 2535.3 2538.5 2563.0 2564.1 2592.2 9765.5 9872.1 9907.5 9914.5 9919.5 5645.0 5783.2 5795.7 5800.4 5802.1 3613.4 3672.2 3750.7 3845.3 3847.5 1142.8 1164.9 1197.7 1206.9 1258.1 14171.5 14175.0 14179.2 14237.0 14267.8 5202.5 5206.9 5270.1 5296.2 5302.5 11772.5 11856.2 11857.1 11862.4 11863.2 5923.0 5924.3 5925.3 5935.2 5937.7 4676.5 5228.4 5354.5 5358.6 5429.0 4328.9 4367.5 4395.0 4432.8 4438.5 1382.2 1394.9 1400.1 1401.2 1426.2 924.1 1030.7 1045.3 1062.7 1086.6 6411.6 6414.7 6518.0 6594.8 6670.5 479.7 497.3 509.2 512.9 513.9 4512.0 4561.7 4580.5 4582.2 4585.9 11916.3 11939.9 12253.6 12365.3 12416.9 12443.8 12716.2 12770.0 12805.8 12816.2 9052.7 9260.0 9270.2 9278.5 9285.2 6706.4 6828.7 6875.0 6987.3 7014.7 11001.2 11004.7 11312.3 11544.5 11610.9 11260.7 11476.2 11604.9 11641.2 11732.3 7889.0 8477.7 8685.6 8708.2 8915.5 9535.9 9568.8 9588.7 9597.3 9627.9 8410.8 8573.4 8612.2 8680.8 8740.0 8320.5 8354.8 8379.1 8380.0 8699.7 7629.5 7630.1 7699.5 7753.3 7797.8 5288.4 5553.8 5558.6 5667.4 5707.3 5561.6 5611.3 5646.1 5648.1 5667.5 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | [#4] prototype.html Cycles:180 Average:4800.67 Median:5407.61 stddev:1407.90 (26.0%) stddev-sans-first:1371.53 04:00:22 INFO - PROCESS | 24910 | Values: 343.7 352.2 355.5 357.6 359.3 5404.3 5450.4 5523.8 5547.2 5560.3 5364.9 5410.9 5449.3 5486.0 5490.0 3825.5 4029.8 4262.3 4267.0 4287.9 4182.7 4211.4 4215.4 4227.7 4227.9 4049.0 4192.2 4233.2 4235.9 4245.4 4085.4 4193.9 4197.9 4211.1 4229.5 3674.0 3705.2 3728.5 3730.3 3777.5 5244.5 5273.9 5274.7 5283.6 5304.2 4867.9 4886.2 4919.9 4930.1 4934.4 5422.8 5540.2 5571.3 5599.2 5734.3 4015.8 4020.0 4084.7 4114.6 4120.3 5810.9 5850.2 5859.3 5873.8 5876.7 4149.6 4299.6 4311.1 4337.5 4339.3 4334.7 4360.3 4396.1 4402.7 4403.4 4951.9 4972.5 4977.7 4986.6 5000.1 5478.8 5702.8 5740.2 5755.0 5781.0 1503.9 1513.2 1515.2 1516.0 1531.6 953.1 954.0 968.3 990.2 990.5 5015.4 5037.8 5057.7 5060.2 5073.2 5219.7 5262.6 5273.4 5280.8 5285.3 5048.7 5073.3 5087.4 5269.8 5708.0 3122.3 3128.5 3133.2 3137.0 3156.5 6202.2 6204.7 6211.9 6213.6 6223.7 5736.9 6033.4 6039.8 6040.3 6049.5 5881.6 6038.3 6191.2 6217.8 6226.7 5825.5 5850.3 5852.9 5875.2 5930.7 5666.9 5816.7 5863.2 5877.5 5882.6 6115.4 6121.1 6131.5 6135.9 6233.4 4194.6 4218.2 4227.5 4236.2 4245.8 5736.3 5857.9 5937.2 5940.9 5943.6 5795.3 5808.4 5808.5 5811.0 5830.0 5521.3 5776.2 5790.4 5801.9 5812.7 5605.6 5621.8 5637.5 5650.2 5657.0 5797.9 5803.8 5808.2 5821.1 5828.1 5490.7 5667.3 5677.2 5709.5 5718.0 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - PROCESS | 24910 | [#5] yui.html Cycles:180 Average:20951.06 Median:4584.96 stddev:29816.76 (650.3%) stddev-sans-first:29861.66 04:00:22 INFO - PROCESS | 24910 | Values: 707.8 737.9 806.3 836.6 848.5 15043.0 15092.6 15093.4 15126.9 15267.4 15437.5 15835.9 15838.8 15854.4 15873.4 713.3 830.8 843.9 853.1 857.5 835.9 839.6 842.3 844.2 844.4 896.4 898.8 898.8 898.9 899.0 743.3 874.3 894.5 895.0 897.2 660.6 661.6 662.2 662.4 662.6 14681.4 14714.3 14721.6 14725.9 14739.0 13432.7 13541.0 13619.3 13672.0 13814.7 12024.1 12267.9 12313.9 12429.6 12447.6 4444.2 4570.4 4599.5 4612.6 4647.9 77229.0 77389.6 77712.6 77942.0 78151.0 11335.8 11442.5 11630.9 11731.9 11788.2 19515.5 19589.0 19590.5 19609.4 19639.7 8435.0 8570.4 8615.7 8894.2 8948.4 4842.3 4890.5 4916.6 4992.3 5022.1 3537.5 3665.6 3670.5 3671.2 3671.4 654.4 660.6 663.4 674.6 676.7 680.3 704.0 706.3 717.4 746.7 10034.9 10060.7 10103.1 10154.0 10257.9 656.0 684.2 691.4 739.8 766.3 4713.2 4732.8 4740.8 4881.5 4935.7 106056.0 109438.0 112707.0 118271.0 121685.0 98951.0 102098.5 102265.5 102315.5 102652.5 59951.0 71511.6 90650.1 90908.5 91488.5 9162.7 9176.4 9320.5 9362.0 9515.1 53269.7 54677.5 55101.7 55256.4 55490.7 67556.7 67749.3 68520.3 77111.6 83214.6 13857.2 13965.4 14030.2 14245.1 14320.1 12189.0 14509.3 15483.9 15768.5 15824.9 13926.8 14114.4 15022.3 15511.6 15964.7 13463.5 14335.6 14478.1 14556.6 15477.0 14300.5 14527.6 14740.9 14743.9 15117.8 10789.4 11254.5 11827.3 11992.6 12134.8 12167.1 12387.8 12529.9 12784.9 12904.4 04:00:22 INFO - PROCESS | 24910 | -------- Summary: end -------- 04:00:22 INFO - PROCESS | 24910 | 04:00:22 INFO - TEST-INFO | 24910: exit 0 04:00:22 INFO - TEST-OK | dromaeo_css | took 1112400ms 04:00:22 INFO - TEST-START | kraken 04:00:22 INFO - operating with platform_type : linux_ 04:00:22 INFO - Initialising browser for kraken test... 04:00:22 INFO - TEST-INFO | started process 25099 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprAE2i2/profile http://localhost:44499/getInfo.html) 04:00:26 INFO - PROCESS | 25099 | __metrics Screen width/height:1600/1200 04:00:26 INFO - PROCESS | 25099 | colorDepth:24 04:00:26 INFO - PROCESS | 25099 | Browser inner width/height: 1024/697 04:00:26 INFO - PROCESS | 25099 | __metrics 04:00:27 INFO - PROCESS | 25099 | 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" 04:00:27 ERROR - PROCESS | 25099 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:00:27 INFO - TEST-INFO | 25099: exit 0 04:00:27 INFO - Browser initialized. 04:00:27 INFO - Running cycle 1/1 for kraken test... 04:00:27 INFO - TEST-INFO | started process 25190 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprAE2i2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:00:39 INFO - PROCESS | 25190 | RSS: Main: 142049280 04:00:39 INFO - PROCESS | 25190 | 04:00:42 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44499/tests/kraken/driver.html?testName=audio-beat-detection) 04:00:43 INFO - PROCESS | 25190 | RSS: Main: 193318912 04:00:43 INFO - PROCESS | 25190 | 04:00:45 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44499/tests/kraken/driver.html?testName=audio-dft) 04:00:46 INFO - PROCESS | 25190 | RSS: Main: 192667648 04:00:46 INFO - PROCESS | 25190 | 04:00:49 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44499/tests/kraken/driver.html?testName=audio-fft) 04:00:49 INFO - PROCESS | 25190 | RSS: Main: 277954560 04:00:49 INFO - PROCESS | 25190 | 04:00:51 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44499/tests/kraken/driver.html?testName=audio-oscillator) 04:00:52 INFO - PROCESS | 25190 | RSS: Main: 186306560 04:00:52 INFO - PROCESS | 25190 | 04:00:54 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44499/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:00:55 INFO - PROCESS | 25190 | RSS: Main: 186826752 04:00:55 INFO - PROCESS | 25190 | 04:00:58 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44499/tests/kraken/driver.html?testName=imaging-darkroom) 04:00:58 INFO - PROCESS | 25190 | RSS: Main: 221663232 04:00:58 INFO - PROCESS | 25190 | 04:01:02 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44499/tests/kraken/driver.html?testName=imaging-desaturate) 04:01:02 INFO - PROCESS | 25190 | RSS: Main: 222130176 04:01:02 INFO - PROCESS | 25190 | 04:01:05 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44499/tests/kraken/driver.html?testName=json-parse-financial) 04:01:06 INFO - PROCESS | 25190 | RSS: Main: 220651520 04:01:06 INFO - PROCESS | 25190 | 04:01:07 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44499/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:01:08 INFO - PROCESS | 25190 | RSS: Main: 199626752 04:01:08 INFO - PROCESS | 25190 | 04:01:09 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:01:10 INFO - PROCESS | 25190 | RSS: Main: 202010624 04:01:10 INFO - PROCESS | 25190 | 04:01:12 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:01:13 INFO - PROCESS | 25190 | RSS: Main: 210653184 04:01:13 INFO - PROCESS | 25190 | 04:01:15 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:01:16 INFO - PROCESS | 25190 | RSS: Main: 213770240 04:01:16 INFO - PROCESS | 25190 | 04:01:18 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:01:19 INFO - PROCESS | 25190 | RSS: Main: 214319104 04:01:19 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | Cycle 1(1): loaded http://localhost:44499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44499/tests/kraken/driver.html?testName=ai-astar) 04:01:21 INFO - PROCESS | 25190 | RSS: Main: 257044480 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | __start_tp_report 04:01:21 INFO - PROCESS | 25190 | _x_x_mozilla_page_load 04:01:21 INFO - PROCESS | 25190 | _x_x_mozilla_page_load_details 04:01:21 INFO - PROCESS | 25190 | |i|pagename|runs| 04:01:21 INFO - PROCESS | 25190 | |0;ai-astar;111;104;112;108;111;110;110;110;109;109 04:01:21 INFO - PROCESS | 25190 | |1;audio-beat-detection;149;121;122;173;122;125;148;125;173;123 04:01:21 INFO - PROCESS | 25190 | |2;audio-dft;161;160;160;161;159;159;160;160;160;160 04:01:21 INFO - PROCESS | 25190 | |3;audio-fft;73;73;75;108;75;75;73;74;96;73 04:01:21 INFO - PROCESS | 25190 | |4;audio-oscillator;83;84;115;85;83;84;118;83;85;124 04:01:21 INFO - PROCESS | 25190 | |5;imaging-gaussian-blur;116;117;117;116;117;117;117;117;116;117 04:01:21 INFO - PROCESS | 25190 | |6;imaging-darkroom;145;145;146;145;145;145;145;145;146;145 04:01:21 INFO - PROCESS | 25190 | |7;imaging-desaturate;94;94;95;94;94;94;94;95;94;94 04:01:21 INFO - PROCESS | 25190 | |8;json-parse-financial;51;50;56;52;54;52;52;53;52;53 04:01:21 INFO - PROCESS | 25190 | |9;json-stringify-tinderbox;61;61;59;60;60;61;60;59;61;62 04:01:21 INFO - PROCESS | 25190 | |10;stanford-crypto-aes;77;80;78;79;82;76;76;83;111;78 04:01:21 INFO - PROCESS | 25190 | |11;stanford-crypto-ccm;120;117;116;117;126;115;117;115;134;127 04:01:21 INFO - PROCESS | 25190 | |12;stanford-crypto-pbkdf2;153;154;150;153;152;157;157;155;153;150 04:01:21 INFO - PROCESS | 25190 | |13;stanford-crypto-sha256-iterative;64;61;60;61;63;64;63;62;60;59 04:01:21 INFO - PROCESS | 25190 | __end_tp_report 04:01:21 INFO - PROCESS | 25190 | __start_cc_report 04:01:21 INFO - PROCESS | 25190 | _x_x_mozilla_cycle_collect,1559 04:01:21 INFO - PROCESS | 25190 | __end_cc_report 04:01:21 INFO - PROCESS | 25190 | __startTimestamp1470049281279__endTimestamp 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | ------- Summary: start ------- 04:01:21 INFO - PROCESS | 25190 | Number of tests: 14 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:2.22 (2.0%) stddev-sans-first:2.28 04:01:21 INFO - PROCESS | 25190 | Values: 111.0 104.0 112.0 108.0 111.0 110.0 110.0 110.0 109.0 109.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:136.50 stddev:21.17 (15.5%) stddev-sans-first:22.09 04:01:21 INFO - PROCESS | 25190 | Values: 149.0 121.0 122.0 173.0 122.0 125.0 148.0 125.0 173.0 123.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.60 04:01:21 INFO - PROCESS | 25190 | Values: 161.0 160.0 160.0 161.0 159.0 159.0 160.0 160.0 160.0 160.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#3] audio-fft Cycles:10 Average:79.50 Median:74.50 stddev:12.22 (16.4%) stddev-sans-first:12.74 04:01:21 INFO - PROCESS | 25190 | Values: 73.0 73.0 75.0 108.0 75.0 75.0 73.0 74.0 96.0 73.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:83.50 stddev:17.13 (20.5%) stddev-sans-first:17.66 04:01:21 INFO - PROCESS | 25190 | Values: 83.0 84.0 115.0 85.0 83.0 84.0 118.0 83.0 85.0 124.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:01:21 INFO - PROCESS | 25190 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 04:01:21 INFO - PROCESS | 25190 | Values: 145.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:01:21 INFO - PROCESS | 25190 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.65 (3.1%) stddev-sans-first:1.66 04:01:21 INFO - PROCESS | 25190 | Values: 51.0 50.0 56.0 52.0 54.0 52.0 52.0 53.0 52.0 53.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:61.00 stddev:0.97 (1.6%) stddev-sans-first:1.00 04:01:21 INFO - PROCESS | 25190 | Values: 61.0 61.0 59.0 60.0 60.0 61.0 60.0 59.0 61.0 62.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:78.50 stddev:10.46 (13.3%) stddev-sans-first:10.93 04:01:21 INFO - PROCESS | 25190 | Values: 77.0 80.0 78.0 79.0 82.0 76.0 76.0 83.0 111.0 78.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#11] stanford-crypto-ccm Cycles:10 Average:120.40 Median:118.50 stddev:6.43 (5.4%) stddev-sans-first:6.82 04:01:21 INFO - PROCESS | 25190 | Values: 120.0 117.0 116.0 117.0 126.0 115.0 117.0 115.0 134.0 127.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.50 stddev:2.46 (1.6%) stddev-sans-first:2.60 04:01:21 INFO - PROCESS | 25190 | Values: 153.0 154.0 150.0 153.0 152.0 157.0 157.0 155.0 153.0 150.0 04:01:21 INFO - PROCESS | 25190 | 04:01:21 INFO - PROCESS | 25190 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.50 stddev:1.77 (2.8%) stddev-sans-first:1.67 04:01:21 INFO - PROCESS | 25190 | Values: 64.0 61.0 60.0 61.0 63.0 64.0 63.0 62.0 60.0 59.0 04:01:21 INFO - PROCESS | 25190 | -------- Summary: end -------- 04:01:21 INFO - PROCESS | 25190 | 04:01:22 INFO - TEST-INFO | 25190: exit 0 04:01:22 INFO - TEST-OK | kraken | took 59578ms 04:01:22 INFO - SUITE-END | took 1171s 04:01:22 INFO - Completed test suite (00:19:32) 04:01:22 INFO - javascript benchmark 04:01:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.1522119470133, 3407.0460271124084, 3420.5382273393093, 3451.2579795343995, 3456.222349971257, 5595.3285605727315, 5701.2018317435595, 5709.371969083398, 5710.914336284954, 5721.771129154834, 5658.207850900877, 5728.656280623166, 5731.484166425032, 5735.1970724098655, 5741.339745929219, 4962.206047032476, 5071.594272458213, 5143.331366922304, 5192.247124166994, 5213.374395072597, 5132.871678208037, 5180.430152683201, 5183.688978661274, 5184.066867963765, 5185.040767458014, 5068.949310506903, 5089.236614507817, 5109.591232701375, 5123.2315152727015, 5178.6892786432745, 5163.664361816489, 5179.404368497633, 5186.377634683843, 5190.792368305301, 5219.4780521947505, 5014.598832093424, 5027.396712394499, 5059.342285502861, 5088.440271570124, 5100.591952643779, 5402.054640437907, 5406.810761623325, 5412.431694672042, 5424.267723857268, 5476.288082549283, 6058.36940693011, 6076.0, 6081.27024757032, 6090.512758979271, 6099.939000609981, 5948.702564871739, 5996.16053752475, 6003.27960644721, 6074.0, 6074.332751309554, 5636.971815140939, 5684.914726279109, 5689.0, 5694.288213973212, 5697.4302569742695, 6080.209572755513, 6156.445919867234, 6167.259928808572, 6171.84570385744, 6175.722092505848, 5896.469317761336, 5926.496247818984, 5936.792212272636, 5986.2517185351835, 6025.427584379477, 6107.8473038173515, 6109.083637454418, 6109.969450152721, 6110.419510146529, 6111.816645500642, 5895.882082358329, 5937.050071988461, 6054.212952316171, 6056.364081771439, 6057.454829065406, 6004.54965877561, 6067.423594758491, 6067.423594758491, 6080.665563394055, 6115.816525504242, 4614.123316569841, 4627.051454451829, 4632.652551058684, 4632.884177895579, 4652.860414187579, 4019.1157945252, 4023.959760402417, 4038.0106873815516, 4115.835366585303, 4134.896627584334, 5684.374718781013, 5706.71466426677, 5709.115087161496, 5712.257406537206, 5736.4550367715, 5902.1441890926435, 5959.642421454727, 5960.37416071315, 5961.195238642815, 5982.880342393215, 5667.688277144796, 5726.656400615976, 5728.513076388554, 5768.74040668171, 5792.913106303409, 5176.145935920531, 5182.559482444034, 5183.377994640592, 5190.11767999441, 5195.038917800219, 6436.388543088399, 6442.130312407765, 6449.161608990801, 6450.709718062604, 6453.612783233021, 5953.1070339449425, 5965.0, 6026.035834266497, 6040.969795150996, 6099.3900609938655, 6344.619322840645, 6387.0, 6397.840053998501, 6398.5201109916015, 6405.807825765234, 5689.0, 5934.406559344031, 5942.257217847769, 5951.642901425928, 5951.880962380642, 5921.703914804157, 5949.791757288474, 5985.0, 6039.335673076046, 6044.90932636002, 5861.531077513874, 5900.793472228452, 5905.734241959036, 5908.497777688945, 5911.674857882857, 5797.101449275369, 5818.6217895836635, 5822.854428639318, 5867.970660146672, 5876.706164691663, 5761.971190144022, 5838.12428135783, 5842.591018628656, 5847.210626565529, 5883.0, 5936.257967754031, 5950.910736339045, 5983.670898100646, 5992.250968628921, 5993.0710739835395, 5698.800541981011, 5768.134779783066, 5812.447817457335, 5839.357670656309, 5855.882882342244], "value": 5574.211773006945, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.604407135362, 640.4748106552627, 653.2879161713731, 663.1875951958848, 671.4326394196905, 32472.512912306305, 44956.77521612391, 46370.76814615931, 46405.767971160094, 46437.30344044842, 48241.51758482414, 48595.75702121484, 48730.0, 48755.51244487554, 48893.51106488934, 8240.958795206045, 9041.3671043027, 9175.44947303164, 9193.678221262257, 10673.466326683674, 10504.947475262614, 10935.781284374309, 10955.726106847313, 10959.178061645369, 10965.890341096607, 10961.671149865517, 11003.22977391583, 11007.174461915349, 11013.394263315515, 11013.724656883562, 8350.0, 10899.673009809718, 10905.073068789163, 11145.832812507819, 11149.72125696856, 10577.894221057844, 10993.725156871062, 10997.34015959045, 11001.339919604849, 11010.779784404347, 46252.53747462516, 46473.53526464726, 46599.0, 46804.5319546807, 46994.29508557374, 3662.322470342995, 3920.1375697346825, 4093.6314633659294, 4221.493420789494, 4463.352813842007, 35408.46887296693, 35576.64423355759, 35888.28223435543, 35959.82020089909, 37116.25767484662, 4680.157571637104, 4711.246200607886, 4719.858404247878, 4723.173444647173, 4868.513148685139, 410030.0, 412304.999999997, 412391.0, 412512.0, 412801.0, 35616.643833561335, 35992.28015439729, 70769.64615176994, 71221.0, 71712.64143679249, 42141.57858421438, 42208.3668744969, 42229.57770422318, 42289.57710422918, 42391.15217695691, 17523.29906803739, 17647.0, 17673.0, 17711.114444277733, 17748.91125544364, 13201.73596528064, 14012.439502419993, 14443.638909027359, 14474.0, 14801.703965920837, 9401.529923503935, 9430.387024843363, 9436.905630943602, 9484.0, 9507.952460237655, 3543.114221444639, 3605.0446631642635, 3610.4042832932796, 3644.9817750911075, 3675.22820207754, 1081.8269076947652, 1089.9019088282093, 1103.674416047268, 1163.6218229075585, 1167.4396289780952, 34289.31421371559, 34508.482372764425, 34869.476957845654, 35106.47340289898, 35593.64406355955, 1128.4132251229314, 1179.351356753782, 1179.5694571481347, 1215.094754407954, 1245.6705959855128, 16332.346706131859, 17059.829401705825, 17089.0, 17209.483715488554, 17623.032675561226, 594607.0, 608577.0, 608701.0, 609217.0, 611118.0, 62425.68787156126, 63342.36657633457, 63472.36527634757, 63626.0, 63696.0, 1398.1121987537986, 1492.6865358274667, 1510.3807438949977, 1524.9771253431206, 1556.4785796757985, 34425.311493769485, 35391.292174156886, 35534.644653553136, 35813.28373432569, 35912.46131308085, 56177.0, 57244.42755572391, 57321.71339143278, 57355.139672905774, 57412.71293643672, 488938.0, 501930.0, 501997.0, 502007.0, 502482.0, 39119.608803912735, 45397.54602454065, 46066.0, 46278.537214627424, 50402.7479862613, 51678.741606291725, 52822.73588632032, 53747.19379209393, 53878.461215387346, 54708.179377308574, 44191.52664877911, 44418.0, 44835.0, 44933.55066449424, 46615.30077048779, 42740.0, 43355.78322108369, 43425.17736347254, 43622.56377436312, 44194.0, 44366.3345049831, 44443.0, 44584.0, 44830.55169448394, 44867.10265794601, 37249.25501490009, 40064.59773907899, 40076.1984760309, 40154.0, 40705.0, 30061.496925153217, 30191.547126792677, 30554.541681875235, 30582.694173057986, 30587.388252235276], "value": 21202.838325098834, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.1964206397132, 433.8437963322289, 435.39914917413967, 438.9714668546544, 439.8526493624636, 18664.813351866476, 19702.605947881053, 20238.29165979191, 21012.579748405024, 21044.158233670634, 19808.108635111417, 20142.093605787737, 20440.591188176226, 20465.897670511666, 20553.794462055375, 5640.633358831673, 5830.387809280028, 5862.88274234515, 5885.264341957255, 5887.793927212549, 5826.21346118275, 5831.212786273863, 5892.734826932777, 5915.438033386821, 5923.526117910556, 5808.680522571257, 5849.181114643953, 5852.444017818321, 5860.619059761123, 5867.442592953663, 5661.49970257882, 5840.211571437865, 5866.70666466675, 5868.76524939002, 5880.323762767293, 5179.741012949337, 5226.137687281594, 5283.6037297202865, 5284.867878303035, 5287.391949925769, 23726.05095796166, 23971.52056958851, 24042.7595724044, 24359.878200609062, 24525.877370613212, 2501.0, 2513.5726926422553, 2630.8947642094295, 2758.9586156207674, 3155.652878183398, 13803.447862085504, 14350.13899166043, 14506.41974321026, 15136.848631513654, 15373.308201130976, 1769.4956937272943, 1891.1206289075647, 2001.6997450382414, 2030.3807338761671, 2057.0, 64669.353306467274, 69434.65282673606, 79500.60249698772, 80181.59909200417, 87613.56193218993, 11013.058255321743, 11828.881711182778, 11947.223430476992, 12045.457954392074, 12110.333931633668, 16958.576035598962, 17105.14474276281, 17117.400890969006, 17157.399491017753, 17157.485275441755, 7022.54353467023, 7044.788656340318, 8637.784055398539, 8767.123287671182, 8968.775780605407, 8701.0, 8716.91283087161, 8752.387332886638, 8860.601272942733, 8864.955675221712, 5064.721440320818, 5106.208537676666, 5117.948820511821, 5149.9742501288, 5209.374875014947, 2011.939641810748, 2071.564971356032, 2082.37528741377, 2096.15105882118, 2144.3459744777992, 962.2686758063782, 1065.4566171252718, 1067.114295135036, 1079.8056349857102, 1091.1543553745753, 19190.232390704496, 19219.711704324447, 19363.90318048401, 19459.318923837596, 19537.90231048864, 1000.0899181744578, 1010.5907107621425, 1021.7037059252733, 1035.699647102347, 1072.8498010278568, 10682.946585267024, 10790.136789056856, 10911.672649820519, 11514.712132196762, 11753.823692644617, 398599.0, 404684.0, 406860.0, 595178.0, 613640.0, 73042.26957730461, 75713.0, 95221.0, 108489.45755271331, 108888.45555772171, 1038.7558923652934, 1066.402814423925, 1087.690008347617, 1089.2484185911537, 1092.7377429416883, 17683.38108166208, 17865.28538858483, 17905.37331193402, 17963.820361796214, 18187.909060455142, 42788.78605606952, 45069.774651126536, 45391.0, 46389.304160438296, 48131.0, 51922.740386297824, 56774.0, 57777.422225778886, 64267.0, 65269.0, 13047.934760326138, 13404.865951340362, 13538.12002219892, 13542.322883855571, 13649.317534123056, 14556.053856499717, 14571.41714331415, 14657.853421465648, 14719.411223550938, 14787.852121478647, 12775.61673149807, 12921.095523313277, 12922.806157907451, 13095.541656042375, 13100.803487947878, 12248.414908082343, 12589.685257868261, 12779.936100319439, 12817.615471535495, 13027.609171724864, 12707.87292127067, 12864.0, 12928.288944107788, 13132.212067275994, 13298.268595227355, 13352.532661356807, 13384.263865487492, 13384.531541396005, 13429.194248345131, 13443.395047222702, 12729.108962372546, 12830.743385132431, 12850.485980560672, 12953.158044727063, 13014.544490942772], "value": 9981.93554533346, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.8943329250308, 1592.3232626133895, 1618.8057433108024, 1821.6994195957668, 1926.2198809482165, 8540.957295213522, 9524.095210954956, 9722.465264410463, 9731.562079706413, 10041.297109202358, 9659.082387173225, 9801.558929848155, 9826.950865245664, 9837.60649574018, 9840.40957542548, 2126.617208902397, 2457.213691618683, 2516.446381796002, 2522.5585522533574, 2567.358160459885, 2547.4395632960814, 2566.3455818766283, 2574.0, 2577.072253988572, 2577.85821779802, 2532.670752802133, 2577.8324408913454, 2579.88390522426, 2581.186926118276, 2583.922482325524, 2081.844576260175, 2410.1203060882654, 2470.6170543565777, 2479.00839664134, 2484.1181380609805, 2535.315464824487, 2538.530371881208, 2562.9615555766677, 2564.064116597447, 2592.2223333000024, 9765.462899540522, 9872.062154095349, 9907.504624768733, 9914.454704991223, 9919.50402479873, 5645.0, 5783.161441590988, 5795.681237531934, 5800.419958004208, 5802.12968054791, 3613.439916812898, 3672.173760903802, 3750.7374483786148, 3845.3270677631303, 3847.5190601174854, 1142.765733024728, 1164.9009834164106, 1197.6586672798298, 1206.8551773787115, 1258.0690289185936, 14171.503997360043, 14175.0, 14179.220142892136, 14237.0, 14267.785983210157, 5202.453742357074, 5206.895862082737, 5270.130428479258, 5296.152615581565, 5302.549283310962, 11772.470238839272, 11856.22934509254, 11857.051435885109, 11862.406879656155, 11863.228890122115, 5923.0, 5924.348321684612, 5925.259342582177, 5935.198748168942, 5937.70311484424, 4676.462206846256, 5228.424873263938, 5354.464553544614, 5358.571314294847, 5429.0, 4328.935065974013, 4367.45406824147, 4394.956050439519, 4432.756198409054, 4438.489573699001, 1382.219046238872, 1394.8884089272833, 1400.0829456705874, 1401.1663060479, 1426.2084543078483, 924.1405492891541, 1030.6907927621683, 1045.2578669144991, 1062.7130674717782, 1086.6088208245026, 6411.647359395185, 6414.711337989802, 6518.022296655445, 6594.802155935329, 6670.466362690973, 479.72655586315466, 497.3360563647499, 509.19292920720335, 512.8846009647874, 513.8612574604836, 4511.977440112844, 4561.703489273186, 4580.450345958439, 4582.221022426195, 4585.862424127282, 11916.344601046852, 11939.88060119405, 12253.571125010581, 12365.258084514779, 12416.937915310365, 12443.751124977449, 12716.173448725804, 12770.042246831339, 12805.807912881315, 12816.166949148275, 9052.728418147466, 9259.95370023159, 9270.212031977222, 9278.489683067495, 9285.164335209864, 6706.362895525112, 6828.692708828214, 6875.037494750639, 6987.336203060701, 7014.719411223698, 11001.22991390595, 11004.669859904216, 11312.26470279462, 11544.480498377761, 11610.94194529022, 11260.662180134608, 11476.221185716227, 11604.88395116038, 11641.243319184226, 11732.296062236293, 7889.0, 8477.660893564434, 8685.565721713763, 8708.216260536583, 8915.465072095436, 9535.904640953779, 9568.760780980252, 9588.66439674608, 9597.28020398459, 9627.90372096298, 8410.831783364421, 8573.356998225034, 8612.224899758801, 8680.78298042554, 8740.03859575459, 8320.50076995358, 8354.832903341778, 8379.120192379713, 8380.0, 8699.652013919373, 7629.504082234637, 7630.084389873251, 7699.461037727306, 7753.340966017726, 7797.7660670179985, 5288.365396152486, 5553.8333849984565, 5558.555315574825, 5667.40492248308, 5707.315122185199, 5561.555075594024, 5611.270534830609, 5646.0683987141665, 5648.096304591246, 5667.546596272371], "value": 5033.856011503887, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.6717934372674, 352.1987478486443, 355.49164694486876, 357.64235764235764, 359.29578027066947, 5404.270423492827, 5450.400455949847, 5523.7790488380415, 5547.169434086752, 5560.332760068793, 5364.861555989741, 5410.945890541094, 5449.329828384093, 5485.9725701371435, 5489.972550137244, 3825.48355971943, 4029.7783621900858, 4262.318029115342, 4267.0, 4287.871363859089, 4182.733611716048, 4211.43145675333, 4215.409842622035, 4227.704060715751, 4227.894302642428, 4049.0, 4192.24539582875, 4233.153369326147, 4235.8729238122905, 4245.384419259206, 4085.385353373843, 4193.874183774492, 4197.874063778091, 4211.052513184509, 4229.534751177368, 3673.9632603674154, 3705.1663375740513, 3728.4780130781714, 3730.2539492101687, 3777.5466943966626, 5244.475552444763, 5273.894522109536, 5274.709890955995, 5283.603729720248, 5304.20436934459, 4867.906800075966, 4886.169351210303, 4919.877003074916, 4930.087933732271, 4934.358533390672, 5422.810201642963, 5540.168974653794, 5571.331440227158, 5599.188117722907, 5734.340550836666, 4015.819288132012, 4020.0, 4084.7344922579937, 4114.6091121343725, 4120.340745480738, 5810.941890581083, 5850.180974663551, 5859.296884373903, 5873.765049398062, 5876.706164691791, 4149.568398902374, 4299.570042995738, 4311.137772445523, 4337.457817772778, 4339.327404252283, 4334.696571240046, 4360.302351623725, 4396.142752163361, 4402.669799765031, 4403.383526306319, 4951.90096198074, 4972.453030166679, 4977.726225057657, 4986.601071914238, 5000.149974504343, 5478.835634930959, 5702.8289151325525, 5740.225069615632, 5755.0, 5780.971095144581, 1503.9398424063133, 1513.1526345246698, 1515.2348064227494, 1515.9691409841414, 1531.5941275561986, 953.0707560128819, 953.9618415263312, 968.3076600230869, 990.2474119669104, 990.4700984973005, 5015.445211984296, 5037.823676171316, 5057.671251368649, 5060.240963855377, 5073.15903094261, 5219.6950762309425, 5262.578993680497, 5273.367195936512, 5280.81517146898, 5285.312909321764, 5048.72232027242, 5073.289739436482, 5087.389513258358, 5269.841904742864, 5708.0, 3122.266267427153, 3128.499440089575, 3133.2323580722414, 3137.0, 3156.510740835174, 6202.193714817045, 6204.689765511706, 6211.9068213976825, 6213.596116252429, 6223.719932602953, 5736.942630573641, 6033.3664965178905, 6039.78860739872, 6040.338906800665, 6049.5462840287155, 5881.5588830837005, 6038.275406951194, 6191.152070047829, 6217.813465596039, 6226.6575338355915, 5825.475707186373, 5850.327212370632, 5852.882942341044, 5875.20684707559, 5930.673812940157, 5666.886662266814, 5816.738246778989, 5863.20846685692, 5877.500412464818, 5882.646891271934, 6115.3578874217565, 6121.112438696453, 6131.478824299985, 6135.9386406137155, 6233.407826256498, 4194.580541945781, 4218.198542276958, 4227.492700875914, 4236.195122926634, 4245.787710614395, 5736.340320863154, 5857.853553661193, 5937.168796368425, 5940.851478713067, 5943.554233432596, 5795.33353664334, 5808.419158084327, 5808.5062769665055, 5811.012127938345, 5830.0, 5521.254630624814, 5776.19133321327, 5790.449907258803, 5801.912971305349, 5812.651240925389, 5605.5515558756015, 5621.775128994795, 5637.46444087811, 5650.180723794944, 5656.971715141398, 5797.884042319214, 5803.7968671096305, 5808.186853840551, 5821.137236084582, 5828.125781132864, 5490.6980116094, 5667.2632557768875, 5677.1768093627015, 5709.51469125129, 5718.0], "value": 4358.412465981992, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.812429706128, 737.8819388897774, 806.3065763443434, 836.6026137584647, 848.537547036865, 15043.0, 15092.622684432894, 15093.396264149435, 15126.924365378158, 15267.389304427823, 15437.45968891089, 15835.920820395912, 15838.84161158388, 15854.365825366971, 15873.365065397398, 713.2546488919093, 830.779843341515, 843.9282660973824, 853.108501615813, 857.5069335132292, 835.8871552340447, 839.6053854688287, 842.3471809347774, 844.2359664503634, 844.4215712237137, 896.3725392225468, 898.7528429681824, 898.7753061734567, 898.9190972812446, 898.993127696977, 743.305009815824, 874.2961915657893, 894.4901406198469, 895.0288936503902, 897.1880448194395, 660.6498555765452, 661.6460193796296, 662.1557514169424, 662.3674390956625, 662.572640646785, 14681.412743490248, 14714.264286785618, 14721.55835324942, 14725.852741472663, 14739.041962272418, 13432.731345373038, 13541.0, 13619.319034048258, 13672.0, 13814.65463363414, 12024.141593623046, 12267.938660306641, 12313.93843030788, 12429.564965226264, 12447.564335248313, 4444.177827101964, 4570.405847239837, 4599.517050709695, 4612.607928326096, 4647.86056418308, 77229.0, 77389.61305193494, 77712.61143694358, 77942.0, 78151.0, 11335.773284534429, 11442.542298307975, 11630.88369116315, 11731.88268117325, 11788.23376480526, 19515.51211219731, 19589.0, 19590.510237244183, 19609.411717648494, 19639.705404418946, 8435.0, 8570.442921210102, 8615.6984505542, 8894.199522043047, 8948.373613846969, 4842.346283251716, 4890.486498917634, 4916.557509824133, 4992.301077849104, 5022.070916880389, 3537.451694987281, 3665.6334366563665, 3670.541182352206, 3671.192337685705, 3671.394219953731, 654.3979538824293, 660.6366498425847, 663.3697986912356, 674.5547938360756, 676.6549059979446, 680.2755065854849, 703.9788806335819, 706.3471476744443, 717.4475653746586, 746.73117677636, 10034.899651003543, 10060.74848128788, 10103.14123299513, 10154.0, 10257.897421025844, 656.0159760359461, 684.2439104789236, 691.4192078653955, 739.8335374540793, 766.3332900376708, 4713.198756211452, 4732.810687572527, 4740.834070807505, 4881.536254055859, 4935.679180853233, 106056.0, 109438.0, 112707.0, 118271.0, 121685.0, 98951.0, 102098.48950755199, 102265.48867255617, 102315.4884225574, 102652.48673756584, 59951.0, 71511.6424417885, 90650.09349906548, 90908.54545727362, 91488.54255728678, 9162.67930622425, 9176.402682960937, 9320.487373194539, 9362.0, 9515.096065873731, 53269.733651331495, 54677.45322546724, 55101.72449137729, 55256.44743552513, 55490.72254638701, 67556.66221668861, 67749.32250677627, 68520.31479685339, 77111.61444192744, 83214.58392708239, 13857.168569885838, 13965.3715582797, 14030.228337441538, 14245.145291282557, 14320.14079155254, 12189.0, 14509.347079381241, 15483.922580387027, 15768.526944191693, 15824.92087539601, 13926.791098133333, 14114.36485358182, 15022.323995420014, 15511.612209694847, 15964.680706385576, 13463.461461541428, 14335.56993290203, 14478.131312121306, 14556.563303100924, 15477.0, 14300.499482518062, 14527.636809079857, 14740.926295368883, 14743.852561474248, 15117.773233401727, 10789.352638841694, 11254.493547790205, 11827.290362578273, 11992.64022079339, 12134.75730485403, 12167.087468440079, 12387.75224495487, 12529.934955528883, 12784.87215127874, 12904.419301131284], "value": 7767.865976350442, "unit": "score"}], "name": "dromaeo_css", "value": 7654.104570773878, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 112.0, 108.0, 111.0, 110.0, 110.0, 110.0, 109.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 122.0, 173.0, 122.0, 125.0, 148.0, 125.0, 173.0, 123.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 159.0, 159.0, 160.0, 160.0, 160.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 108.0, 75.0, 75.0, 73.0, 74.0, 96.0, 73.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 115.0, 85.0, 83.0, 84.0, 118.0, 83.0, 85.0, 124.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 56.0, 52.0, 54.0, 52.0, 52.0, 53.0, 52.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 60.0, 60.0, 61.0, 60.0, 59.0, 61.0, 62.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 80.0, 78.0, 79.0, 82.0, 76.0, 76.0, 83.0, 111.0, 78.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 116.0, 117.0, 126.0, 115.0, 117.0, 115.0, 134.0, 127.0], "value": 120.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 150.0, 153.0, 152.0, 157.0, 157.0, 155.0, 153.0, 150.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 60.0, 61.0, 63.0, 64.0, 63.0, 62.0, 60.0, 59.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1467.9000000000003, "alertThreshold": 2.0}]} 04:01:22 INFO - Return code: 0 04:01:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:01:22 ERROR - # TBPL SUCCESS # 04:01:22 INFO - Running post-action listener: _resource_record_post_action 04:01:22 INFO - ##### 04:01:22 INFO - ##### Finished run-tests step (success) 04:01:22 INFO - ##### 04:01:22 INFO - Running post-run listener: _resource_record_post_run 04:01:22 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 496947200; Read time: 4; Write time: 466892 04:01:22 INFO - TinderboxPrint: CPU usage
13.1% 04:01:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 04:01:22 INFO - TinderboxPrint: I/O write bytes / time
496,947,200 / 466,892 04:01:22 INFO - TinderboxPrint: CPU idle
8,248.4 (86.9%) 04:01:22 INFO - TinderboxPrint: CPU user
1,196.0 (12.6%) 04:01:22 INFO - TinderboxPrint: Swap in / out
0 / 0 04:01:22 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 248066048; Read time: 0; Write time: 328140 04:01:22 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248475648; Read time: 4; Write time: 138392 04:01:23 INFO - Running post-run listener: _upload_blobber_files 04:01:23 INFO - Blob upload gear active. 04:01:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:01:23 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 04:01:23 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'] 04:01:23 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 04:01:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:01:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:01:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:01:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:24 INFO - (blobuploader) - INFO - Done attempting. 04:01:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:01:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:01:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:28 INFO - (blobuploader) - INFO - Done attempting. 04:01:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:01:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:01:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:29 INFO - (blobuploader) - INFO - Done attempting. 04:01:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:01:29 INFO - Return code: 0 04:01:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:01:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:01:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f94a4338d3a39bc28c7f55f039c66db462c1666da21472e59c581ba578b8b0d01e66863bb8301bcd2cc094b05902979b3c6d125309a93a31977d1d1c33d2466", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/787127077d7cbc4091f3e0d1187c213e60060aff2ee9d9330ae377bde95fe7a9e9adddc8b17fd2bc3192e6a75ae305260de30b8e56f7b8955803087043884069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccf396888adfe14963f49752c879335e992b0fa623b7b1a3ac01b2f86e5a8d21b19e45ba63569c15a20458f0583944855d3f651c941f9ccbbe7ba070ab701317"} 04:01:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:01:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:01:29 INFO - Contents: 04:01:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f94a4338d3a39bc28c7f55f039c66db462c1666da21472e59c581ba578b8b0d01e66863bb8301bcd2cc094b05902979b3c6d125309a93a31977d1d1c33d2466", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/787127077d7cbc4091f3e0d1187c213e60060aff2ee9d9330ae377bde95fe7a9e9adddc8b17fd2bc3192e6a75ae305260de30b8e56f7b8955803087043884069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccf396888adfe14963f49752c879335e992b0fa623b7b1a3ac01b2f86e5a8d21b19e45ba63569c15a20458f0583944855d3f651c941f9ccbbe7ba070ab701317"} 04:01:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:01:29 INFO - Copying logs to upload dir... 04:01:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:01:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.554065 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-08-01 04:01:27.376301) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:27.382348) ========= 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=826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151 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/9f94a4338d3a39bc28c7f55f039c66db462c1666da21472e59c581ba578b8b0d01e66863bb8301bcd2cc094b05902979b3c6d125309a93a31977d1d1c33d2466", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/787127077d7cbc4091f3e0d1187c213e60060aff2ee9d9330ae377bde95fe7a9e9adddc8b17fd2bc3192e6a75ae305260de30b8e56f7b8955803087043884069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccf396888adfe14963f49752c879335e992b0fa623b7b1a3ac01b2f86e5a8d21b19e45ba63569c15a20458f0583944855d3f651c941f9ccbbe7ba070ab701317"} build_url:https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011402 build_url: 'https://queue.taskcluster.net/v1/task/eLMM3hp0RVmJf3oSKD1zTQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f94a4338d3a39bc28c7f55f039c66db462c1666da21472e59c581ba578b8b0d01e66863bb8301bcd2cc094b05902979b3c6d125309a93a31977d1d1c33d2466", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/787127077d7cbc4091f3e0d1187c213e60060aff2ee9d9330ae377bde95fe7a9e9adddc8b17fd2bc3192e6a75ae305260de30b8e56f7b8955803087043884069", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccf396888adfe14963f49752c879335e992b0fa623b7b1a3ac01b2f86e5a8d21b19e45ba63569c15a20458f0583944855d3f651c941f9ccbbe7ba070ab701317"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:27.412406) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:27.413081) ========= 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=826874ccc97bd7bf541b4875000001d5-1470047754.563059-224576151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 04:01:27.497789) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 04:01:27.498108) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 04:01:27.498402) ========= ========= Total master_lag: 0.20 =========