builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1475691327.29 results: failure (2) buildid: 20161005073022 builduid: 6b89adacaa5e49cfb7676f3a71050950 revision: e8fa13708c070d1fadf488ed9d951464745b4e17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.287355) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.288547) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.289277) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.306192) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.306833) ========= 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=14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-05 11:15:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.1M=0s 2016-10-05 11:15:28 (88.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.411641 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.734906) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.735286) ========= 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=14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015957 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:27.788518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 11:15:27.789552) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e8fa13708c070d1fadf488ed9d951464745b4e17 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e8fa13708c070d1fadf488ed9d951464745b4e17 --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=14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-05 11:15:28,225 truncating revision to first 12 chars 2016-10-05 11:15:28,225 Setting DEBUG logging. 2016-10-05 11:15:28,225 attempt 1/10 2016-10-05 11:15:28,225 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e8fa13708c07?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 11:15:29,072 unpacking tar archive at: mozilla-central-e8fa13708c07/testing/mozharness/ program finished with exit code 0 elapsedTime=1.196827 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 11:15:29.005238) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:29.005954) ========= script_repo_revision: e8fa13708c070d1fadf488ed9d951464745b4e17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:29.006935) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:29.007661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 11:15:29.019976) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 46 secs) (at 2016-10-05 11:15:29.020300) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:15:29 INFO - MultiFileLogger online at 20161005 11:15:29 in /builds/slave/test 11:15:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:15:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:15:29 INFO - {'append_to_log': False, 11:15:29 INFO - 'base_work_dir': '/builds/slave/test', 11:15:29 INFO - 'blob_upload_branch': 'Firefox', 11:15:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:15:29 INFO - 'branch': 'Firefox', 11:15:29 INFO - 'buildbot_json_path': 'buildprops.json', 11:15:29 INFO - 'config_files': ('talos/linux_config.py',), 11:15:29 INFO - 'default_actions': ('clobber', 11:15:29 INFO - 'read-buildbot-config', 11:15:29 INFO - 'download-and-extract', 11:15:29 INFO - 'populate-webroot', 11:15:29 INFO - 'create-virtualenv', 11:15:29 INFO - 'install', 11:15:29 INFO - 'run-tests'), 11:15:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:15:29 INFO - 'download_minidump_stackwalk': True, 11:15:29 INFO - 'download_symbols': 'ondemand', 11:15:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:15:29 INFO - 'tooltool.py': '/tools/tooltool.py', 11:15:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:15:29 INFO - '/tools/misc-python/virtualenv.py')}, 11:15:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:15:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:15:29 INFO - 'installer_path': 'installer.exe', 11:15:29 INFO - 'log_level': 'info', 11:15:29 INFO - 'log_name': 'talos', 11:15:29 INFO - 'log_to_console': True, 11:15:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:15:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:15:29 INFO - 'opt_config_files': (), 11:15:29 INFO - 'pip_index': False, 11:15:29 INFO - 'sps_profile': False, 11:15:29 INFO - 'sps_profile_interval': 0, 11:15:29 INFO - 'suite': 'dromaeojs', 11:15:29 INFO - 'system_bits': '32', 11:15:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:15:29 INFO - 'title': 'talos-linux64-ix-036', 11:15:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:15:29 INFO - 'use_talos_json': True, 11:15:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:15:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:15:29 INFO - 'work_dir': 'build'} 11:15:29 INFO - [mozharness: 2016-10-05 18:15:29.552339Z] Running clobber step. 11:15:29 INFO - Running pre-action listener: _resource_record_pre_action 11:15:29 INFO - Running main action method: clobber 11:15:29 INFO - rmtree: /builds/slave/test/build 11:15:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:15:30 INFO - Running post-action listener: _resource_record_post_action 11:15:30 INFO - [mozharness: 2016-10-05 18:15:30.537740Z] Finished clobber step (success) 11:15:30 INFO - [mozharness: 2016-10-05 18:15:30.537840Z] Running read-buildbot-config step. 11:15:30 INFO - Running pre-action listener: _resource_record_pre_action 11:15:30 INFO - Running main action method: read_buildbot_config 11:15:30 INFO - Using buildbot properties: 11:15:30 INFO - { 11:15:30 INFO - "project": "", 11:15:30 INFO - "product": "firefox", 11:15:30 INFO - "script_repo_revision": "production", 11:15:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 11:15:30 INFO - "repository": "", 11:15:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 11:15:30 INFO - "buildid": "20161005073022", 11:15:30 INFO - "pgo_build": "True", 11:15:30 INFO - "basedir": "/builds/slave/test", 11:15:30 INFO - "buildnumber": 18, 11:15:30 INFO - "slavename": "talos-linux64-ix-036", 11:15:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:15:30 INFO - "platform": "ubuntu64_hw", 11:15:30 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 11:15:30 INFO - "branch": "mozilla-central", 11:15:30 INFO - "repo_path": "mozilla-central", 11:15:30 INFO - "revision": "e8fa13708c070d1fadf488ed9d951464745b4e17", 11:15:30 INFO - "stage_platform": "linux64-pgo", 11:15:30 INFO - "builduid": "6b89adacaa5e49cfb7676f3a71050950", 11:15:30 INFO - "slavebuilddir": "test" 11:15:30 INFO - } 11:15:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 11:15:30 INFO - Running post-action listener: _resource_record_post_action 11:15:30 INFO - [mozharness: 2016-10-05 18:15:30.540261Z] Finished read-buildbot-config step (success) 11:15:30 INFO - [mozharness: 2016-10-05 18:15:30.540350Z] Running download-and-extract step. 11:15:30 INFO - Running pre-action listener: _resource_record_pre_action 11:15:30 INFO - Running main action method: download_and_extract 11:15:30 INFO - mkdir: /builds/slave/test/build/tests 11:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:30 INFO - trying https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 11:15:30 INFO - Downloading https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 11:15:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:15:31 INFO - Downloaded 1361 bytes. 11:15:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 11:15:31 INFO - Using the following test package requirements: 11:15:31 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 11:15:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:15:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:15:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'jsshell-linux-x86_64.zip'], 11:15:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:15:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 11:15:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:15:31 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:15:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:15:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 11:15:31 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:15:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:15:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 11:15:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:15:31 INFO - Fetch https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:15:31 INFO - Http code: 200 11:15:31 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 11:15:31 INFO - x-amz-cf-id: iKW_p-SFIqK2L4xuGSS-qNfFYKmC4PfRuDdSBFf4j6I5PEgbYOSYwQ== 11:15:31 INFO - x-amz-version-id: FnoFcih2zHYMLvVMh9jZWxhLXaBmP.aj 11:15:32 INFO - Expected file size: 21992742 11:15:32 INFO - Obtained file size: 21992742 11:15:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:15:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 11:15:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:15:36 INFO - Fetch https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:15:36 INFO - Http code: 200 11:15:36 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 11:15:36 INFO - x-amz-cf-id: Goy0yw8lsn-9v9sFnejoo1ypwIN7jkB0RYCTh4Qokqm3429rFf0mQA== 11:15:36 INFO - x-amz-version-id: FnoFcih2zHYMLvVMh9jZWxhLXaBmP.aj 11:15:36 INFO - Expected file size: 21992742 11:15:36 INFO - Obtained file size: 21992742 11:15:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 11:15:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:15:41 INFO - Fetch https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 11:15:41 INFO - Http code: 200 11:15:41 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 11:15:41 INFO - x-amz-cf-id: Np-ULdwIaI_afFheLrSXCP6UjRz46tABdvk0FXkuL4QlTIIQLYuq7A== 11:15:41 INFO - x-amz-version-id: GCDypArHmRZoWUldCpzaVUMkIGGomxyd 11:15:42 INFO - Expected file size: 14409006 11:15:42 INFO - Obtained file size: 14409006 11:15:42 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:42 INFO - trying https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:15:42 INFO - Downloading https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:15:43 INFO - Downloaded 59696514 bytes. 11:15:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:15:43 INFO - mkdir: /builds/slave/test/properties 11:15:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:15:43 INFO - Writing to file /builds/slave/test/properties/build_url 11:15:43 INFO - Contents: 11:15:43 INFO - build_url:https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 11:15:44 INFO - Running post-action listener: _resource_record_post_action 11:15:44 INFO - Running post-action listener: set_extra_try_arguments 11:15:44 INFO - [mozharness: 2016-10-05 18:15:44.196398Z] Finished download-and-extract step (success) 11:15:44 INFO - [mozharness: 2016-10-05 18:15:44.196694Z] Running populate-webroot step. 11:15:44 INFO - Running pre-action listener: _resource_record_pre_action 11:15:44 INFO - Running main action method: populate_webroot 11:15:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:15:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:15:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:15:44 INFO - '--tppagecycles', 11:15:44 INFO - '7'], 11:15:44 INFO - 'tests': ['tsvgm']}}, 11:15:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:15:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:15:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:15:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:15:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['damp', 'tps']}, 11:15:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'tests': ['damp', 'tps']}, 11:15:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['dromaeo_dom']}, 11:15:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:15:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['basic_compositor_video']}, 11:15:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:15:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['a11yr', 11:15:44 INFO - 'ts_paint', 11:15:44 INFO - 'tpaint', 11:15:44 INFO - 'sessionrestore', 11:15:44 INFO - 'sessionrestore_no_auto_restore', 11:15:44 INFO - 'tabpaint']}, 11:15:44 INFO - 'other-e10s': {'tests': ['a11yr', 11:15:44 INFO - 'ts_paint', 11:15:44 INFO - 'tpaint', 11:15:44 INFO - 'sessionrestore', 11:15:44 INFO - 'sessionrestore_no_auto_restore', 11:15:44 INFO - 'tabpaint']}, 11:15:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:15:44 INFO - 'ts_paint', 11:15:44 INFO - 'tpaint', 11:15:44 INFO - 'sessionrestore', 11:15:44 INFO - 'sessionrestore_no_auto_restore', 11:15:44 INFO - 'tabpaint']}, 11:15:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:15:44 INFO - 'ts_paint', 11:15:44 INFO - 'tpaint', 11:15:44 INFO - 'sessionrestore', 11:15:44 INFO - 'sessionrestore_no_auto_restore', 11:15:44 INFO - 'tabpaint']}, 11:15:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['a11yr', 11:15:44 INFO - 'ts_paint', 11:15:44 INFO - 'tpaint', 11:15:44 INFO - 'sessionrestore', 11:15:44 INFO - 'sessionrestore_no_auto_restore', 11:15:44 INFO - 'tabpaint']}, 11:15:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['a11yr', 11:15:44 INFO - 'ts_paint', 11:15:44 INFO - 'tpaint', 11:15:44 INFO - 'sessionrestore', 11:15:44 INFO - 'sessionrestore_no_auto_restore', 11:15:44 INFO - 'tabpaint']}, 11:15:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['tsvgx', 11:15:44 INFO - 'tsvgr_opacity', 11:15:44 INFO - 'tart', 11:15:44 INFO - 'tscrollx', 11:15:44 INFO - 'cart']}, 11:15:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:15:44 INFO - 'tsvgr_opacity', 11:15:44 INFO - 'tart', 11:15:44 INFO - 'tscrollx', 11:15:44 INFO - 'cart']}, 11:15:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'talos_options': ['--disable-e10s'], 11:15:44 INFO - 'tests': ['tp5o']}, 11:15:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'tests': ['tp5o']}, 11:15:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'talos_options': ['--disable-e10s', 11:15:44 INFO - '--xperf_path', 11:15:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:44 INFO - 'tests': ['tp5n']}, 11:15:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:15:44 INFO - 'talos_options': ['--xperf_path', 11:15:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:44 INFO - 'tests': ['tp5n']}}, 11:15:44 INFO - 'talos.zip': {'path': '', 11:15:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:15:44 INFO - Running post-action listener: _resource_record_post_action 11:15:44 INFO - [mozharness: 2016-10-05 18:15:44.219588Z] Finished populate-webroot step (success) 11:15:44 INFO - [mozharness: 2016-10-05 18:15:44.219699Z] Running create-virtualenv step. 11:15:44 INFO - Running pre-action listener: _resource_record_pre_action 11:15:44 INFO - Running main action method: create_virtualenv 11:15:44 INFO - Creating virtualenv /builds/slave/test/build/venv 11:15:44 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 11:15:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:15:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:15:44 INFO - Using real prefix '/usr' 11:15:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:15:44 INFO - Installing distribute.............................................................................................................................................................................................done. 11:15:46 INFO - Installing pip.................done. 11:15:46 INFO - Return code: 0 11:15:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:15:46 INFO - Reading from file tmpfile_stdout 11:15:46 INFO - Output received: 11:15:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:15:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:47 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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:15:47 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 11:15:47 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 11:15:47 INFO - Using env: {'DISPLAY': ':0', 11:15:47 INFO - 'HOME': '/home/cltbld', 11:15:47 INFO - 'LANG': 'en_US.UTF-8', 11:15:47 INFO - 'LANGUAGE': 'en_US:en', 11:15:47 INFO - 'LOGNAME': 'cltbld', 11:15:47 INFO - 'MAIL': '/var/mail/cltbld', 11:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:47 INFO - 'MOZ_NO_REMOTE': '1', 11:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:47 INFO - 'NO_EM_RESTART': '1', 11:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:47 INFO - 'PWD': '/builds/slave/test', 11:15:47 INFO - 'SHELL': '/bin/bash', 11:15:47 INFO - 'SHLVL': '1', 11:15:47 INFO - 'TERM': 'linux', 11:15:47 INFO - 'TMOUT': '86400', 11:15:47 INFO - 'USER': 'cltbld', 11:15:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:47 INFO - '_': '/tools/buildbot/bin/python'} 11:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:47 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 11:15:47 INFO - Cleaning up... 11:15:47 INFO - Return code: 0 11:15:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:15:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:47 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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:15:47 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 11:15:47 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 11:15:47 INFO - Using env: {'DISPLAY': ':0', 11:15:47 INFO - 'HOME': '/home/cltbld', 11:15:47 INFO - 'LANG': 'en_US.UTF-8', 11:15:47 INFO - 'LANGUAGE': 'en_US:en', 11:15:47 INFO - 'LOGNAME': 'cltbld', 11:15:47 INFO - 'MAIL': '/var/mail/cltbld', 11:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:47 INFO - 'MOZ_NO_REMOTE': '1', 11:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:47 INFO - 'NO_EM_RESTART': '1', 11:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:47 INFO - 'PWD': '/builds/slave/test', 11:15:47 INFO - 'SHELL': '/bin/bash', 11:15:47 INFO - 'SHLVL': '1', 11:15:47 INFO - 'TERM': 'linux', 11:15:47 INFO - 'TMOUT': '86400', 11:15:47 INFO - 'USER': 'cltbld', 11:15:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:47 INFO - '_': '/tools/buildbot/bin/python'} 11:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:47 INFO - Downloading/unpacking psutil>=3.1.1 11:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:15:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:49 INFO - Installing collected packages: psutil 11:15:49 INFO - Running setup.py install for psutil 11:15:49 INFO - building 'psutil._psutil_linux' extension 11:15:49 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 11:15:49 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 11:15:49 INFO - building 'psutil._psutil_posix' extension 11:15:49 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 11:15:49 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 11:15:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:49 INFO - Successfully installed psutil 11:15:49 INFO - Cleaning up... 11:15:50 INFO - Return code: 0 11:15:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:15:50 INFO - Using env: {'DISPLAY': ':0', 11:15:50 INFO - 'HOME': '/home/cltbld', 11:15:50 INFO - 'LANG': 'en_US.UTF-8', 11:15:50 INFO - 'LANGUAGE': 'en_US:en', 11:15:50 INFO - 'LOGNAME': 'cltbld', 11:15:50 INFO - 'MAIL': '/var/mail/cltbld', 11:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:50 INFO - 'MOZ_NO_REMOTE': '1', 11:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:50 INFO - 'NO_EM_RESTART': '1', 11:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:50 INFO - 'PWD': '/builds/slave/test', 11:15:50 INFO - 'SHELL': '/bin/bash', 11:15:50 INFO - 'SHLVL': '1', 11:15:50 INFO - 'TERM': 'linux', 11:15:50 INFO - 'TMOUT': '86400', 11:15:50 INFO - 'USER': 'cltbld', 11:15:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:50 INFO - '_': '/tools/buildbot/bin/python'} 11:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:15:52 INFO - Installing collected packages: mozsystemmonitor 11:15:52 INFO - Running setup.py install for mozsystemmonitor 11:15:52 INFO - Successfully installed mozsystemmonitor 11:15:52 INFO - Cleaning up... 11:15:52 INFO - Return code: 0 11:15:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:15:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:15:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:15:52 INFO - Using env: {'DISPLAY': ':0', 11:15:52 INFO - 'HOME': '/home/cltbld', 11:15:52 INFO - 'LANG': 'en_US.UTF-8', 11:15:52 INFO - 'LANGUAGE': 'en_US:en', 11:15:52 INFO - 'LOGNAME': 'cltbld', 11:15:52 INFO - 'MAIL': '/var/mail/cltbld', 11:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:52 INFO - 'MOZ_NO_REMOTE': '1', 11:15:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:52 INFO - 'NO_EM_RESTART': '1', 11:15:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:52 INFO - 'PWD': '/builds/slave/test', 11:15:52 INFO - 'SHELL': '/bin/bash', 11:15:52 INFO - 'SHLVL': '1', 11:15:52 INFO - 'TERM': 'linux', 11:15:52 INFO - 'TMOUT': '86400', 11:15:52 INFO - 'USER': 'cltbld', 11:15:52 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:52 INFO - '_': '/tools/buildbot/bin/python'} 11:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:52 INFO - Downloading/unpacking jsonschema==2.5.1 11:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:15:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:15:54 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 11:15:54 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 11:15:54 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 11:15:54 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 11:15:54 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 11:15:54 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 11:15:54 INFO - Downloading functools32-3.2.3-2.tar.gz 11:15:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:15:54 INFO - warning: no files found matching '*.txt' 11:15:54 INFO - no previously-included directories found matching 'build' 11:15:54 INFO - no previously-included directories found matching 'dist' 11:15:54 INFO - no previously-included directories found matching '.git*' 11:15:54 INFO - Installing collected packages: jsonschema, functools32 11:15:54 INFO - Running setup.py install for functools32 11:15:54 INFO - warning: no files found matching '*.txt' 11:15:54 INFO - no previously-included directories found matching 'build' 11:15:54 INFO - no previously-included directories found matching 'dist' 11:15:54 INFO - no previously-included directories found matching '.git*' 11:15:54 INFO - Successfully installed jsonschema functools32 11:15:54 INFO - Cleaning up... 11:15:54 INFO - Return code: 0 11:15:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:15:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:15:54 INFO - Using env: {'DISPLAY': ':0', 11:15:54 INFO - 'HOME': '/home/cltbld', 11:15:54 INFO - 'LANG': 'en_US.UTF-8', 11:15:54 INFO - 'LANGUAGE': 'en_US:en', 11:15:54 INFO - 'LOGNAME': 'cltbld', 11:15:54 INFO - 'MAIL': '/var/mail/cltbld', 11:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:54 INFO - 'MOZ_NO_REMOTE': '1', 11:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:54 INFO - 'NO_EM_RESTART': '1', 11:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:54 INFO - 'PWD': '/builds/slave/test', 11:15:54 INFO - 'SHELL': '/bin/bash', 11:15:54 INFO - 'SHLVL': '1', 11:15:54 INFO - 'TERM': 'linux', 11:15:54 INFO - 'TMOUT': '86400', 11:15:54 INFO - 'USER': 'cltbld', 11:15:54 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:54 INFO - '_': '/tools/buildbot/bin/python'} 11:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:15:55 INFO - Cleaning up... 11:15:55 INFO - Return code: 0 11:15:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:15:55 INFO - Using env: {'DISPLAY': ':0', 11:15:55 INFO - 'HOME': '/home/cltbld', 11:15:55 INFO - 'LANG': 'en_US.UTF-8', 11:15:55 INFO - 'LANGUAGE': 'en_US:en', 11:15:55 INFO - 'LOGNAME': 'cltbld', 11:15:55 INFO - 'MAIL': '/var/mail/cltbld', 11:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:55 INFO - 'MOZ_NO_REMOTE': '1', 11:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:55 INFO - 'NO_EM_RESTART': '1', 11:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:55 INFO - 'PWD': '/builds/slave/test', 11:15:55 INFO - 'SHELL': '/bin/bash', 11:15:55 INFO - 'SHLVL': '1', 11:15:55 INFO - 'TERM': 'linux', 11:15:55 INFO - 'TMOUT': '86400', 11:15:55 INFO - 'USER': 'cltbld', 11:15:55 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:55 INFO - '_': '/tools/buildbot/bin/python'} 11:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:55 INFO - Downloading/unpacking blobuploader==1.2.4 11:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:58 INFO - Downloading blobuploader-1.2.4.tar.gz 11:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:15:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:15:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:59 INFO - Downloading docopt-0.6.1.tar.gz 11:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:15:59 INFO - Installing collected packages: blobuploader, requests, docopt 11:15:59 INFO - Running setup.py install for blobuploader 11:15:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:15:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:15:59 INFO - Running setup.py install for requests 11:15:59 INFO - Running setup.py install for docopt 11:15:59 INFO - Successfully installed blobuploader requests docopt 11:15:59 INFO - Cleaning up... 11:15:59 INFO - Return code: 0 11:15:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:59 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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:15:59 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 11:15:59 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 11:15:59 INFO - Using env: {'DISPLAY': ':0', 11:15:59 INFO - 'HOME': '/home/cltbld', 11:15:59 INFO - 'LANG': 'en_US.UTF-8', 11:15:59 INFO - 'LANGUAGE': 'en_US:en', 11:15:59 INFO - 'LOGNAME': 'cltbld', 11:15:59 INFO - 'MAIL': '/var/mail/cltbld', 11:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:59 INFO - 'MOZ_NO_REMOTE': '1', 11:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:59 INFO - 'NO_EM_RESTART': '1', 11:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:59 INFO - 'PWD': '/builds/slave/test', 11:15:59 INFO - 'SHELL': '/bin/bash', 11:15:59 INFO - 'SHLVL': '1', 11:15:59 INFO - 'TERM': 'linux', 11:15:59 INFO - 'TMOUT': '86400', 11:15:59 INFO - 'USER': 'cltbld', 11:15:59 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:59 INFO - '_': '/tools/buildbot/bin/python'} 11:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:00 INFO - Running setup.py (path:/tmp/pip-590rQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:00 INFO - Running setup.py (path:/tmp/pip-11f6LU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:00 INFO - Running setup.py (path:/tmp/pip-BcA2U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:00 INFO - Running setup.py (path:/tmp/pip-eTM7NE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:00 INFO - Running setup.py (path:/tmp/pip-pHG0UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:00 INFO - Running setup.py (path:/tmp/pip-I3Tn59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:00 INFO - Running setup.py (path:/tmp/pip-vR_nFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:00 INFO - Running setup.py (path:/tmp/pip-DmqRcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:00 INFO - Running setup.py (path:/tmp/pip-cxbo9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:00 INFO - Running setup.py (path:/tmp/pip-bRy4Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:00 INFO - Running setup.py (path:/tmp/pip-Edm1u4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:00 INFO - Running setup.py (path:/tmp/pip-mTgURG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:00 INFO - Running setup.py (path:/tmp/pip-NL3KTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:01 INFO - Running setup.py (path:/tmp/pip-BesBql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:01 INFO - Running setup.py (path:/tmp/pip-6XlO65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:01 INFO - Running setup.py (path:/tmp/pip-DM3bXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:01 INFO - Running setup.py (path:/tmp/pip-B4kJ0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:16:01 INFO - Running setup.py install for manifestparser 11:16:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:16:01 INFO - Running setup.py install for mozcrash 11:16:01 INFO - Running setup.py install for mozdebug 11:16:01 INFO - Running setup.py install for mozdevice 11:16:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:16:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:16:01 INFO - Running setup.py install for mozfile 11:16:01 INFO - Running setup.py install for mozhttpd 11:16:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:16:01 INFO - Running setup.py install for mozinfo 11:16:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:16:01 INFO - Running setup.py install for mozInstall 11:16:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Running setup.py install for mozleak 11:16:02 INFO - Running setup.py install for mozlog 11:16:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Running setup.py install for moznetwork 11:16:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Running setup.py install for mozprocess 11:16:02 INFO - Running setup.py install for mozprofile 11:16:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Running setup.py install for mozrunner 11:16:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:16:02 INFO - Running setup.py install for mozscreenshot 11:16:02 INFO - Running setup.py install for moztest 11:16:02 INFO - Running setup.py install for mozversion 11:16:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:16:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:16:03 INFO - Cleaning up... 11:16:03 INFO - Return code: 0 11:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:03 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:03 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:03 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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:16:03 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 11:16:03 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 11:16:03 INFO - Using env: {'DISPLAY': ':0', 11:16:03 INFO - 'HOME': '/home/cltbld', 11:16:03 INFO - 'LANG': 'en_US.UTF-8', 11:16:03 INFO - 'LANGUAGE': 'en_US:en', 11:16:03 INFO - 'LOGNAME': 'cltbld', 11:16:03 INFO - 'MAIL': '/var/mail/cltbld', 11:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:03 INFO - 'MOZ_NO_REMOTE': '1', 11:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:03 INFO - 'NO_EM_RESTART': '1', 11:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:03 INFO - 'PWD': '/builds/slave/test', 11:16:03 INFO - 'SHELL': '/bin/bash', 11:16:03 INFO - 'SHLVL': '1', 11:16:03 INFO - 'TERM': 'linux', 11:16:03 INFO - 'TMOUT': '86400', 11:16:03 INFO - 'USER': 'cltbld', 11:16:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:03 INFO - '_': '/tools/buildbot/bin/python'} 11:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:03 INFO - Running setup.py (path:/tmp/pip-ST40rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:03 INFO - Running setup.py (path:/tmp/pip-PEnAIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:03 INFO - Running setup.py (path:/tmp/pip-P6ALEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:03 INFO - Running setup.py (path:/tmp/pip-QN0t5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:03 INFO - Running setup.py (path:/tmp/pip-oS9YXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:03 INFO - Running setup.py (path:/tmp/pip-Ou1jSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:03 INFO - Running setup.py (path:/tmp/pip-_NQibu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:03 INFO - Running setup.py (path:/tmp/pip-P4KZAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:03 INFO - Running setup.py (path:/tmp/pip-RdADWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:03 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)) 11:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:03 INFO - Running setup.py (path:/tmp/pip-RhrtlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:04 INFO - Running setup.py (path:/tmp/pip-ZAkNRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:04 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)) 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:04 INFO - Running setup.py (path:/tmp/pip-sjW0UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:04 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)) 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:04 INFO - Running setup.py (path:/tmp/pip-G66Rvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:04 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)) 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:04 INFO - Running setup.py (path:/tmp/pip-YBnjBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:04 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)) 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:04 INFO - Running setup.py (path:/tmp/pip-m1lebF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:04 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)) 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:04 INFO - Running setup.py (path:/tmp/pip-cjVfKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:04 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)) 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:04 INFO - Running setup.py (path:/tmp/pip-XzqQju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:04 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)) 11:16:04 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)) 11:16:04 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)) 11:16:04 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)) 11:16:04 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)) 11:16:04 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)) 11:16:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:16:04 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 11:16:04 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 11:16:04 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 11:16:04 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 11:16:04 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 11:16:04 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 11:16:06 INFO - Downloading blessings-1.6.tar.gz 11:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:16:06 INFO - Installing collected packages: blessings 11:16:06 INFO - Running setup.py install for blessings 11:16:06 INFO - Successfully installed blessings 11:16:06 INFO - Cleaning up... 11:16:06 INFO - Return code: 0 11:16:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:16:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:06 INFO - Reading from file tmpfile_stdout 11:16:06 INFO - Current package versions: 11:16:06 INFO - argparse == 1.2.1 11:16:06 INFO - blessings == 1.6 11:16:06 INFO - blobuploader == 1.2.4 11:16:06 INFO - docopt == 0.6.1 11:16:06 INFO - functools32 == 3.2.3-2 11:16:06 INFO - jsonschema == 2.5.1 11:16:06 INFO - manifestparser == 1.1 11:16:06 INFO - mozInstall == 1.12 11:16:06 INFO - mozcrash == 0.17 11:16:06 INFO - mozdebug == 0.1 11:16:06 INFO - mozdevice == 0.48 11:16:06 INFO - mozfile == 1.2 11:16:06 INFO - mozhttpd == 0.7 11:16:06 INFO - mozinfo == 0.9 11:16:06 INFO - mozleak == 0.1 11:16:06 INFO - mozlog == 3.4 11:16:06 INFO - moznetwork == 0.27 11:16:06 INFO - mozprocess == 0.23 11:16:06 INFO - mozprofile == 0.28 11:16:06 INFO - mozrunner == 6.12 11:16:06 INFO - mozscreenshot == 0.1 11:16:06 INFO - mozsystemmonitor == 0.3 11:16:06 INFO - moztest == 0.7 11:16:06 INFO - mozversion == 1.4 11:16:06 INFO - psutil == 3.1.1 11:16:06 INFO - requests == 1.2.3 11:16:06 INFO - wsgiref == 0.1.2 11:16:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:06 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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:16:06 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 11:16:06 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 11:16:06 INFO - Using env: {'DISPLAY': ':0', 11:16:06 INFO - 'HOME': '/home/cltbld', 11:16:06 INFO - 'LANG': 'en_US.UTF-8', 11:16:06 INFO - 'LANGUAGE': 'en_US:en', 11:16:06 INFO - 'LOGNAME': 'cltbld', 11:16:06 INFO - 'MAIL': '/var/mail/cltbld', 11:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:06 INFO - 'MOZ_NO_REMOTE': '1', 11:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:06 INFO - 'NO_EM_RESTART': '1', 11:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:06 INFO - 'PWD': '/builds/slave/test', 11:16:06 INFO - 'SHELL': '/bin/bash', 11:16:06 INFO - 'SHLVL': '1', 11:16:06 INFO - 'TERM': 'linux', 11:16:06 INFO - 'TMOUT': '86400', 11:16:06 INFO - 'USER': 'cltbld', 11:16:06 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:06 INFO - '_': '/tools/buildbot/bin/python'} 11:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 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)) 11:16:07 INFO - Cleaning up... 11:16:07 INFO - Return code: 0 11:16:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:07 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 0x7f6f4b4def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e4790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, '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': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', '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 11:16:07 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 11:16:07 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 11:16:07 INFO - Using env: {'DISPLAY': ':0', 11:16:07 INFO - 'HOME': '/home/cltbld', 11:16:07 INFO - 'LANG': 'en_US.UTF-8', 11:16:07 INFO - 'LANGUAGE': 'en_US:en', 11:16:07 INFO - 'LOGNAME': 'cltbld', 11:16:07 INFO - 'MAIL': '/var/mail/cltbld', 11:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:07 INFO - 'MOZ_NO_REMOTE': '1', 11:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:07 INFO - 'NO_EM_RESTART': '1', 11:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:07 INFO - 'PWD': '/builds/slave/test', 11:16:07 INFO - 'SHELL': '/bin/bash', 11:16:07 INFO - 'SHLVL': '1', 11:16:07 INFO - 'TERM': 'linux', 11:16:07 INFO - 'TMOUT': '86400', 11:16:07 INFO - 'USER': 'cltbld', 11:16:07 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:07 INFO - '_': '/tools/buildbot/bin/python'} 11:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:16:07 INFO - Cleaning up... 11:16:07 INFO - Return code: 0 11:16:07 INFO - Running post-action listener: _resource_record_post_action 11:16:07 INFO - Running post-action listener: _start_resource_monitoring 11:16:07 INFO - Starting resource monitoring. 11:16:07 INFO - [mozharness: 2016-10-05 18:16:07.467913Z] Finished create-virtualenv step (success) 11:16:07 INFO - [mozharness: 2016-10-05 18:16:07.468414Z] Running install step. 11:16:07 INFO - Running pre-action listener: _resource_record_pre_action 11:16:07 INFO - Running main action method: install 11:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:07 INFO - Reading from file tmpfile_stdout 11:16:07 INFO - Detecting whether we're running mozinstall >=1.0... 11:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:16:07 INFO - Reading from file tmpfile_stdout 11:16:07 INFO - Output received: 11:16:07 INFO - Usage: mozinstall [options] installer 11:16:07 INFO - Options: 11:16:07 INFO - -h, --help show this help message and exit 11:16:07 INFO - -d DEST, --destination=DEST 11:16:07 INFO - Directory to install application into. [default: 11:16:07 INFO - "/builds/slave/test"] 11:16:07 INFO - --app=APP Application being installed. [default: firefox] 11:16:07 INFO - mkdir: /builds/slave/test/build/application 11:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:16:24 INFO - Reading from file tmpfile_stdout 11:16:24 INFO - Output received: 11:16:24 INFO - /builds/slave/test/build/application/firefox/firefox 11:16:24 INFO - Running post-action listener: _resource_record_post_action 11:16:24 INFO - [mozharness: 2016-10-05 18:16:24.787151Z] Finished install step (success) 11:16:24 INFO - [mozharness: 2016-10-05 18:16:24.787395Z] Running run-tests step. 11:16:24 INFO - Running pre-action listener: _resource_record_pre_action 11:16:24 INFO - Running main action method: run_tests 11:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:16:24 INFO - Python 2.7.3 11:16:24 INFO - Return code: 0 11:16:24 INFO - grabbing minidump binary from tooltool 11:16:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:24 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 0x22e1930>, '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 0x22e2830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e2cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:16:24 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 11:16:24 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 11:16:24 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 11:16:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:16:24 INFO - Return code: 0 11:16:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:16:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:16:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:16:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:16:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 11:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 11:16:24 INFO - Using env: {'DISPLAY': ':0', 11:16:24 INFO - 'HOME': '/home/cltbld', 11:16:24 INFO - 'LANG': 'en_US.UTF-8', 11:16:24 INFO - 'LANGUAGE': 'en_US:en', 11:16:24 INFO - 'LOGNAME': 'cltbld', 11:16:24 INFO - 'MAIL': '/var/mail/cltbld', 11:16:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:24 INFO - 'MOZ_NO_REMOTE': '1', 11:16:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:24 INFO - 'NO_EM_RESTART': '1', 11:16:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:24 INFO - 'PWD': '/builds/slave/test', 11:16:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:24 INFO - 'SHELL': '/bin/bash', 11:16:24 INFO - 'SHLVL': '1', 11:16:24 INFO - 'TERM': 'linux', 11:16:24 INFO - 'TMOUT': '86400', 11:16:24 INFO - 'USER': 'cltbld', 11:16:24 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506', 11:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:24 INFO - '_': '/tools/buildbot/bin/python'} 11:16:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 11:16:25 INFO - mozversion application_buildid: 20161005073022 11:16:25 INFO - mozversion application_changeset: e8fa13708c070d1fadf488ed9d951464745b4e17 11:16:25 INFO - mozversion application_display_name: Nightly 11:16:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:16:25 INFO - mozversion application_name: Firefox 11:16:25 INFO - mozversion application_remotingname: firefox 11:16:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:16:25 INFO - mozversion application_vendor: Mozilla 11:16:25 INFO - mozversion application_version: 52.0a1 11:16:25 INFO - mozversion platform_buildid: 20161005073022 11:16:25 INFO - mozversion platform_changeset: e8fa13708c070d1fadf488ed9d951464745b4e17 11:16:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:16:25 INFO - mozversion platform_version: 52.0a1 11:16:25 INFO - using testdate: 1475691385 11:16:25 INFO - actual date: 1475691385 11:16:25 INFO - starting webserver on 'localhost:50533' 11:16:25 INFO - SUITE-START | Running 2 tests 11:16:25 INFO - TEST-START | dromaeo_css 11:16:25 INFO - operating with platform_type : linux_ 11:16:25 INFO - Initialising browser for dromaeo_css test... 11:16:25 INFO - TEST-INFO | started process 1094 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkLrOL1/profile http://localhost:50533/getInfo.html) 11:16:29 INFO - PROCESS | 1094 | __metrics Screen width/height:1600/1200 11:16:29 INFO - PROCESS | 1094 | colorDepth:24 11:16:29 INFO - PROCESS | 1094 | Browser inner width/height: 1024/697 11:16:29 INFO - PROCESS | 1094 | __metrics 11:16:30 INFO - PROCESS | 1094 | 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" 11:16:30 INFO - PROCESS | 1094 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:16:30 INFO - PROCESS | 1094 | console.error: PushService: 11:16:30 INFO - PROCESS | 1094 | stateChangeProcessEnqueue: Error transitioning state 11:16:30 INFO - PROCESS | 1094 | UnknownError 11:16:31 INFO - TEST-INFO | 1094: exit 0 11:16:31 INFO - Browser initialized. 11:16:31 INFO - Running cycle 1/1 for dromaeo_css test... 11:16:31 INFO - TEST-INFO | started process 1188 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkLrOL1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:43 INFO - PROCESS | 1188 | RSS: Main: 173400064 11:16:43 INFO - PROCESS | 1188 | 11:17:35 INFO - PROCESS | 1188 | 1475691455252 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] 11:19:44 INFO - PROCESS | 1188 | Cycle 1(1): loaded http://localhost:50533/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50533/tests/dromaeo/cssquery-ext.html) 11:19:46 INFO - PROCESS | 1188 | RSS: Main: 309329920 11:19:46 INFO - PROCESS | 1188 | 11:22:49 INFO - PROCESS | 1188 | Cycle 1(1): loaded http://localhost:50533/tests/dromaeo/cssquery-ext.html (next: http://localhost:50533/tests/dromaeo/cssquery-jquery.html) 11:22:50 INFO - PROCESS | 1188 | RSS: Main: 275369984 11:22:50 INFO - PROCESS | 1188 | 11:25:51 INFO - PROCESS | 1188 | Cycle 1(1): loaded http://localhost:50533/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50533/tests/dromaeo/cssquery-mootools.html) 11:25:52 INFO - PROCESS | 1188 | RSS: Main: 220327936 11:25:52 INFO - PROCESS | 1188 | 11:28:54 INFO - PROCESS | 1188 | Cycle 1(1): loaded http://localhost:50533/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50533/tests/dromaeo/cssquery-prototype.html) 11:28:55 INFO - PROCESS | 1188 | RSS: Main: 278216704 11:28:55 INFO - PROCESS | 1188 | 11:31:57 INFO - PROCESS | 1188 | Cycle 1(1): loaded http://localhost:50533/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50533/tests/dromaeo/cssquery-yui.html) 11:31:58 INFO - PROCESS | 1188 | RSS: Main: 220647424 11:31:58 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | Cycle 1(1): loaded http://localhost:50533/tests/dromaeo/cssquery-yui.html (next: http://localhost:50533/tests/dromaeo/cssquery-dojo.html) 11:35:01 INFO - PROCESS | 1188 | RSS: Main: 311209984 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | __start_tp_report 11:35:01 INFO - PROCESS | 1188 | _x_x_mozilla_page_load 11:35:01 INFO - PROCESS | 1188 | _x_x_mozilla_page_load_details 11:35:01 INFO - PROCESS | 1188 | |i|pagename|runs| 11:35:01 INFO - PROCESS | 1188 | |0;dojo.html;3011.051518771587;3038.5594088857115;3049.8017628854122;3083.0596668016246;3086.6758990306016;5076.187809950408;5173.767180476878;5231.633785635002;5296.3114795076635;5320.042392369372;5185.170372740362;5255.316808814859;5271.130263506517;5310.867228319294;5327.147656374981;4750.61995040396;4759.7144171349655;4761.357216775743;4800.039992001613;4830.879228019293;4880.8047678092835;4904.362432883738;4926.482719314474;4930.6795058321095;4967.155583550805;4722.409698787652;4764.166270902579;4827.951720482785;4904.656674032819;4934.679245849026;4731.5268473152755;4763.214069678499;4775.498572649856;4783.402074740658;4864.124457597631;4704.670673052853;4727.7163370197895;4741.810327586927;4756.595689366408;4795.880102997453;5100.081985242655;5199.922001169985;5205.765740541685;5213.895722085537;5279.208118782175;5486.561075113981;5574.944250557483;5581.6930068846195;5623.578231632603;5654.180143879114;5667.3482549506925;5688.60179787415;5740.655560666331;5753.050746626802;5753.280839895013;5285.682859028394;5312.973435132838;5315.521603055666;5317.016351974936;5321.308229930123;5484.202733654953;5611.410801865745;5765.56758243125;5806.151070936493;5864.560157988082;5492.780288788484;5499.202615620713;5537.169424586264;5537.446255374511;5608.663480191201;5619.80330688424;5636.267285252891;5640.71796410186;5640.915386269209;5644.520215781622;5384.730763461811;5611.523020543218;5618.6628802272;5621.269234999424;5625.128105143709;5537.446255374431;5556.888862222733;5609.915851262234;5614.494695477427;5645.915311270334;4383.912321753547;4386;4429.313456414261;4431.8670439886855;4443.866683999485;3904.863329783444;3911.647951684305;3914.393269043303;3915.92168156641;3964.5638979712207;5053.595712343077;5148.794048238028;5194.298769666122;5383.838484845461;5429.837104886859;5517.944820551743;5574.108142697149;5578.219049333096;5627.240322556484;5640.238567793295;5219.5041471060185;5268.841934741964;5269.1569348903995;5275.551578115903;5285.735713214401;4684.929726054112;4777.665563410528;4884.242942343943;4890.119778439844;4940.258961155855;5723.77104915807;5732.140178973104;5739.770409183586;5787.797427090031;5860.736266868086;5546.61173717852;5594.104943209067;5595.804146854841;5603.635763675348;5609.859753506195;5568;5757.050086735641;5782.132680098019;5787.131930210502;5796.855078623068;5381.461853814587;5392.433794451526;5444.972775136099;5447.945520544744;5532.640378375393;5518.972405138109;5562.527185189304;5569.470900264469;5571.303587051619;5586.273784408001;5435.374931882726;5437.4562543745305;5438.673679579238;5445.292112025411;5448.645838020516;5290.65610735301;5323.6273460857365;5332.893342133058;5422.457754224546;5426.186072089218;5394.2448057272795;5412.269343638558;5416.566674666096;5417.1874218865905;5419.34967803866;5504.284443022381;5537.1140617501005;5538.307711536058;5622.971885140548;5625.606207565431;5358.812441564526;5372.785088596413;5389.649672771257;5399.163129714901;5413.350397952271 11:35:01 INFO - PROCESS | 1188 | |1;ext.html;594.4501336263957;615.4860691322746;632.6615260835454;632.9715162817679;634.8222497700642;31339.373212535735;41974.79012604937;42604;42773.358399623954;46695;44668.553314466844;45043.324350134695;45044.54955450445;45069;45149.54850451495;8695.34784891134;8900.376973611817;8903.332250081237;8913.59888805002;8914.465132092095;7952.721654742085;8137.104918458995;10418.739531511697;10580.206484513654;10611.204159688015;10703.464826758669;10778.730531736692;10789.730256743565;10797.40614266215;10800.837987430195;10550.314229575053;10642.09542188915;10645;10668.626598069108;10688.679339619825;10459.215558833042;10475.476226188737;10507.159427245802;10529.684109476728;10531.315464494783;41426.1714765706;41531.58468415338;41698;41796.164076718596;41827.37258941088;3603.117236277129;3624.9275014499826;3680.5215322008235;3791.317562838688;4041.191761647682;31165.37669246625;31573.684263157305;31614.683853161634;31707.36585268282;31826.204344891332;4149.668026557868;4282.264502724757;4356.346548017791;4363.105563359503;4463.888402789924;321274;324134;326796;327838;328702;54639.45360546344;55208.06254874081;55385;55388.16917746237;55508.16737748937;56900;57169.714151428976;57339.71330143323;57445;57705.42294577084;16239.51281461558;16438.342466301452;16576.502704918872;16577.585560360847;16581.91709041447;11006.339619622848;11193.66419007431;11216.158788090763;11308.491117899714;11411.714707132389;13887.236202008784;14007.929960350337;14045.367958441895;15007.174605396807;15488.22558872052;5457.536109430743;6117.632942023492;6155;6257.405546473078;6292.4966002719675;959.3140904253353;1009.6213919780082;1016.7559785651538;1125.3979121170134;1172.894439500432;31278.374432511224;31283;31390.84304578508;31433.8428307857;32011.83994080061;1120.2326406411635;1140.8973192412723;1141.2410746853416;1161.3380373187372;1165.7493638867684;15076;16165.110918899338;16282.348706051625;16357.42749003803;16692;471687;477295;480583;499421;552822;49994.250086248736;50570;53217;53700.19449708257;54174.45825541773;1341.0545565376385;1341.8591047940022;1366.3851266929723;1366.938487768053;1371.6982263902125;29620.593631732627;31233.375332493677;31576;31584.36831263316;32132.518012229368;47586.286205707635;47978;47999.76000119977;48010.27984580164;48036.279455808886;438787;439510;440601;441200;441438;26017.73982260229;26799.598006030315;26951.191464256102;27331.180064598095;27348.179554613394;43273.56726432696;43295.78352108345;43688.78155609201;45638.543614564755;47388.289175663085;35327.64672353244;35952.82023589865;36601.08497287589;37119.814400927826;37396.81301593475;37895.81052094832;38347.424788627635;38568.80715596404;39761.60238397579;40320.798396007835;38224;38339;38995.22009559736;39129.804350979204;39353.60646393614;32683.673163268064;34964.300713986086;35342;35401.40693668843;35647.465288021216;25984;26273.34316642023;26439.867800660875;26498.870474400414;26709.732902670723 11:35:01 INFO - PROCESS | 1188 | |2;jquery.html;370.279805777762;375.90226541099304;385.67025193459585;396.3104198694272;413.6752649170402;14126.538222498446;14396.640083997892;16907;19612.70580941287;19674.311399101036;18269.36057237997;18486.815131848678;18528.44414667562;18668;18728.906355468236;5397.082495975684;5609;5610.719464026783;5615.298087739033;5618.494335509803;4732.574068333849;5117.360329958755;5563.443655634444;5583.246261754672;5605.8598535036745;5570.303712035996;5572.7770889164385;5574.358948720908;5580.553555715554;5583.916241256385;5352.250684904097;5516.503514683678;5535.889282214333;5543.1408131739645;5544.750486228129;4789.185838407479;4974.328465657126;4982.626303027251;4990.65065445419;4991.126552853236;20462.181512739473;21268.893655531778;22097.66853497199;22572.548549029092;22602.434939126488;2430.3924018995253;2668.799840012007;2870.942581148386;2892.971070289312;2911.737943585066;9813.558389872473;10028.147607453375;10072.597096116147;10663.733406664818;10672.146228301717;1718.6304944437006;1798.9460316190534;1888.1692055495676;1892.6251337830047;1944.445832937606;77316;81045.59477202635;82661;82802.58598706967;84496.57751711202;10462.529186186639;10602.522886470128;10660.573577056832;10947.781044379068;11185.161112916563;14983.625409364853;15102.093874367572;15107.697846043018;15164.165970871487;15185.316660750294;7641.465097443237;7724.806879827936;7973.20267973198;7977.5612341321785;8108.39187060961;7395.408367330709;7461.179270280265;7472.439567032494;7687;7724.304812566896;4579.313103034571;4613.169629466728;4622.14490319292;4713;4831.323614693945;1872.1762424533433;1971.6253911756721;1980.2573735113297;1995.600879824041;1998.450426132796;409.786910806379;426.67785821705036;445.52106485528185;455.6741929520343;829.6225217526011;11316.660500185008;11736.94131529354;12287.1399002069;12317.445714942849;12384.75230495385;449.61782484887584;454.13487306680827;455.6992385025867;463.7542102685582;480.0327340409078;4997.675151115166;6263.185785847811;6477.8704425912165;6521.608703477807;6629.867402652017;406394;408100;408276;419329;421070;54068;81228;93288.53355733314;94697.52651236838;95134.5243273793;455.6127291802008;456.27680126999076;463.63836207758004;465.5460925597583;465.76711644177914;10722.303050301705;10764.192685548458;11008.284461509977;11070.723231919266;11129.777404452028;38072.80963595275;39108.413373798845;40129.1974160521;40482;40961.361545537846;53916.460835391146;55269.17096243479;58237;58521;59178;7164.2119366868565;7256.383207427217;7373.225811289737;7404;7582.507137036296;11838.112141589243;12303.815442768544;12529.812052819398;12893.871061289266;12946.158499697489;10328.483575821028;10752.677419677422;10824.24230303871;10901.781964360827;11138.88861111411;10080.395176289447;10702.09032232269;10875.836862447228;10899.727506812393;11166.33002019849;10654.307470014424;10667.51996160159;10688.83966740484;11024.44877756125;11244.55021799119;7450.92549074524;7508.0990281166605;7581.294208803196;7731.845363092594;7790.649420776192;9372.39079459833;10543.103836174012;10568.735781605521;10574.2598018138;11190.496427660612 11:35:01 INFO - PROCESS | 1188 | |3;mootools.html;1344.2337867415577;1410.5274732964456;1441.997811520993;1541.4990128208328;1731.7315816048517;7721.6139193040335;8660.74017779466;8993.460392376446;9004.954975225131;9210.21713154381;8940.642374305036;9033.638654453815;9034.096590340963;9108.180263776276;9113.726588202348;2252.8197744180425;2284.1776960294283;2293.7935585797277;2296.47181148336;2296.7243930728337;2165.9783402166013;2218.9889050554807;2223.6442169252923;2228.899699513526;2229.5317983223545;2082.7188329575465;2093.0476633131966;2094.068139677844;2106.147010460766;2144.549644574641;2205.845590808644;2210.9226177083724;2211.4250294923277;2214.9889250553642;2220;2096.8846713430753;2126.213301078611;2127.755308139568;2129.606022885771;2136.4552039229898;8968.699538566936;8981.506017169053;9017.098290170996;9025.909740902573;9062.184403403726;5213.869653258661;5409.594280428983;5413.323334583177;5415.1335786274;5425.104857698475;3425.537552430415;3466.791992480459;3480.1995541025453;3495.493153492729;3532.116970757311;1098.527633117759;1105.2705214558434;1110.7945030169444;1127.2334812327697;1135.3131355529952;11344;11346.773064538746;11441.084713222923;11510.769784604345;11521.251118677343;4102.384642303678;4221.324588065956;4292.892677683083;4311.805968731414;4375.934360984587;9403.106704863027;9410.858837117448;9425.340226184102;9426.95286523563;9430.339876208736;4929.7781599828095;4969.130402179604;4975.751212439363;4975.925361119586;4987.775550100254;4038.5557588665224;4112.691548133902;4532.116237333688;4615.169269531517;4637.930431043537;3678.5102033676403;3728.664420202195;3765.792881391495;3785.8107094645156;3833.9808300958316;1119.3339962722168;1151.0963893343685;1165.7610189911047;1168.3595075130268;1176.4823477669797;903.3315346643568;906.5648488725449;926.2312280806914;939.0044960808569;952.7756832470243;5764.827055188351;5961.731722072517;6060.272767267956;6103.389661033861;6470.029495575607;959.3332633819568;1004.1364426593124;1012.584840215508;1013.0173731480452;1066.338869900651;3900.6684431823046;4030.6976976726864;4051.2910240708347;4075.857344992911;4208.768517731554;11176.552937882394;11367.602133925273;11417.42912854354;11493.252938558968;11556.248843825124;11280;11316;11322;11329.150313726503;11357.716057098638;8292.17078292166;8354.624041918129;8360.247577718035;8368.707095251637;8368.916310836936;5772.538196944319;5862.648241105548;5867.413258674099;5931.555133365101;6017.518598512021;10020.24848136378;10150.137238334824;10333.534990925276;10344.27590068688;10384.325018874051;10406.635767748092;10483.947580262355;10513.684589462022;10585.523651435858;10589;6673.432758215412;6830.112085428863;7094.11323584552;7653.158152603098;7763.922360776546;8407.411481196257;8489.235968762841;8514;8523.48859068433;8530.530820804915;7292.854142917218;7461.4776965614055;7525.397968162424;7669.7315593953945;7683.923160768544;7403.555786652818;7576.356009739234;7579.355754760686;7696.769096927102;7901.802454938673;6844.726210951506;6947.478939079489;6955.130608673916;6959.617221052688;6963.1296087989;4582.198115329737;4886;4908.94457691598;4934.753262336941;5043.44522102561;4833.680609083506;4913.3121363008495;4916.115099282163;5039.546440820343;5046.066477701637 11:35:01 INFO - PROCESS | 1188 | |4;prototype.html;311.7381399624315;337.2664454810786;337.7551275325388;338.2085918949658;343.0925202838493;5027.888366709567;5036.798528058874;5175.9906818170475;5190.027789440008;5259.500347466989;5157.638965272433;5158.303629010083;5158.535731784139;5162.070827251093;5170.922436163461;4093.3246014407587;4105.856295029675;4107.7535347879075;4126;4144.6684265258855;4022.5261464199452;4069.6540794032544;4095.1809638072355;4139.254934111858;4142.627163555279;4064.796760161995;4073.674106071507;4080;4086.366613174963;4098.262312783698;4052.6555242804397;4062.6546743526687;4063.1467391847746;4076.898077548085;4088.5093788745244;3622.2755448910325;3630.618785027587;3631.164832088608;3631.4189729643394;3649.7736380807687;4905.730184839832;4999.275105109775;5011.874703132415;5045.8738531537;5046.268291097807;4561.521040290755;4568.725876447391;4570.474395444533;4575.176468235716;4576.679632425731;5393.4876186762585;5405.432424596862;5406.243125962368;5416.295881535375;5465.453454654543;3723.9518264954436;3908.609139086069;3912.6478616924614;3915.843366265346;3963.7819919904396;5264.657797243167;5451.64564303323;5534.225208470817;5566.801113560162;5575.219469274305;3901.239258344652;3970.146418520032;3992.181602771425;3993.520777506718;3999.9400008999887;4039.5152581690386;4043.6765058795813;4059.3099173140636;4060.817263223162;4068.5931406859077;4604.171249175113;4617.376654151713;4620.775494493962;4629.398178236875;4633.745144017112;5190.221466779938;5395.379531353866;5396.325459317585;5402.540784033323;5403.945960540423;1388.7639101352795;1406.2827957741627;1406.6694326833185;1410.70920108102;1415.5824031910406;862.8050150541545;899.9190072893471;908.9409188402733;912.3385545479474;923.5751554284969;4589.242774942097;4614.330922016289;4634.026854360622;4642.53574642533;4653.95346046542;4848.054629347314;4864.635152363587;4869.174059727602;4880.633952453581;4929.595065406342;4461.152381047591;4622.768861556909;4653.394099304401;4694.835680751157;4709.481956984729;2568.730283320262;2574.6395504629368;2578.4456341886585;2592.468543948524;2618.279973007438;4982.327385802942;5004.874878128003;5019.247112933089;5025.623078269145;5033.899322013539;4741.739204343725;4924.35983322173;4974.482782751224;4986.526280003394;5019.974900125476;4939.703038087307;4951.8266860659705;4985.3826740961285;4990.525900039491;5007.39911210657;4863.513648635108;4902.338184345068;4923.261510773413;4957;4966.776495057803;4521.299198624219;4792.394547826517;4817.614590832655;4845.394325709286;4849.515048495122;4955.45489996093;5000.449950505515;5444.428335024923;5531.778728850962;5593.300837395325;3991.640752332304;3992.800359982047;3996.7817861087374;4013.799310034545;4031.9596804031585;5588.329400471969;5598.748056337393;5607.523360514402;5616.297962754656;5617.43825617435;5153.819616313411;5332.146856502941;5342.412334643109;5360.705161216015;5462.672239665632;5357.8392648220615;5358.651687640291;5371.892562148813;5383.138697808332;5405.297311349499;5342;5343.877785665054;5352.002878388115;5404.810831621032;5408.37803652585;5166.638335316492;5465.672059676433;5480.232767412645;5480.616356855141;5484.753186106715;5316.734163291897;5327.360716714019;5336.546393556591;5337.599680024091;5389.164679474688 11:35:01 INFO - PROCESS | 1188 | |5;yui.html;640.6060966660716;672.5561129654429;680.1338455818565;721.3399739238597;741.584712560966;14563.781543276858;15049.398024079024;15553.300101495432;15589.220538973048;15610;14878.776818347706;15164.772528412082;15249;15270.770938435933;15298.84701152988;790.2926880442016;809.522381794741;811.8741595052746;811.8741595052746;812.2202685422002;676.9120014398121;681.5842336174929;805.963731632078;807.4711064252924;813.1543195077113;866.8266346730647;867.4708427859001;868.5006121480143;869.443556124081;869.8086420987372;867.4058270085012;867.600903584352;868.1275318305095;868.179570306061;868.6525389844049;504.98737531561636;509.28191250336846;512.6846989101713;608.9847753806146;611.3366996808423;13805.930970345184;13822.447102115902;13875.37560809766;13876.861231387658;13885.236312002837;13293.534726284533;14224.288785560784;14232.217228052454;14256.073355231876;14360;12513.687157821036;12579.685507862285;12585.119041667087;12630.242185468902;12663.430145643468;4194.643455306272;4267.76234891881;4269.6370808481315;4304.5364576043985;4317.17973585021;77660.22339776586;77762.6111869437;77784.8332275022;77889.61055194745;77900;9981.650642227632;10057.899421005697;10283.53724082418;10364.326318789406;10620.840687389695;19824.60350792976;20168.596628067357;20170;20214.59570808605;20265.49336266605;8319.584020798935;8390.706325278587;8564.186402291773;8608.526531040852;8685.652573896974;4313.956860431418;4408.713433626868;4580.290055041474;4587.839425620087;4679.672422930362;3367.343368043257;3472.409690352646;3488.773229740059;3488.89533314001;3513.771604845677;612.1643956000028;619.5353484886335;624.5908929651085;642.5341627320156;644.8387903024244;645.2643985856128;645.3901063495045;649.4187702006677;673.0057111045958;680.9693563789641;8304.460210086325;8617.569121543898;8693.217610415093;8934.374593778373;9216.400933939272;572.1132245020202;646.8835609590225;665.4676258992884;666.7932940788371;675.5845155229597;4015.477987861559;4292.635126014324;4433.831520384453;4446.733196008186;4573.63410927125;93283.53358233166;99440;111627.44186279179;122875.38562307131;122892;89156.5542172298;90330.60576087287;94691;95601;95731;76780.61609692028;80697;85194;85280.57359713162;85978.99999999875;8781.912180878366;9223.631054757734;9265.397749146285;9270.304727145625;9335.206507446943;50184;50663.49336506588;50704;51498.74250628723;51571.484285156665;49234.507654924426;54533.45466545284;63843.680781595795;64324.35675643184;69553.65223173852;12359.814602780785;12469.189502682297;12565.748685026065;12591;13184.406701698255;13984.790228146789;14059.789103163255;14108.506202282872;14141.151530908179;14720.558383248092;11651.359175245443;11950.163488555718;12369.876301237233;12619.810702839648;12906.096573239784;12449.564265251034;12456.43946022413;13110.737785244433;13135.737285254432;13195.274259915797;11284.492197851281;11651.184417090722;11671.59149429766;11694.415279236175;12024.338661373358;9465.290103242422;9804.26468014916;9832.70501884916;9915.504224788587;10101.14140298083;9774.218062555121;10084.596616135272;10298.897011029794;10310.175185985254;10413.47932603382 11:35:01 INFO - PROCESS | 1188 | __end_tp_report 11:35:01 INFO - PROCESS | 1188 | __start_cc_report 11:35:01 INFO - PROCESS | 1188 | _x_x_mozilla_cycle_collect,1875 11:35:01 INFO - PROCESS | 1188 | __end_cc_report 11:35:01 INFO - PROCESS | 1188 | __startTimestamp1475692501808__endTimestamp 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | ------- Summary: start ------- 11:35:01 INFO - PROCESS | 1188 | Number of tests: 6 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | [#0] dojo.html Cycles:165 Average:5211.91 Median:5392.43 stddev:567.45 (10.5%) stddev-sans-first:542.29 11:35:01 INFO - PROCESS | 1188 | Values: 3011.1 3038.6 3049.8 3083.1 3086.7 5076.2 5173.8 5231.6 5296.3 5320.0 5185.2 5255.3 5271.1 5310.9 5327.1 4750.6 4759.7 4761.4 4800.0 4830.9 4880.8 4904.4 4926.5 4930.7 4967.2 4722.4 4764.2 4828.0 4904.7 4934.7 4731.5 4763.2 4775.5 4783.4 4864.1 4704.7 4727.7 4741.8 4756.6 4795.9 5100.1 5199.9 5205.8 5213.9 5279.2 5486.6 5574.9 5581.7 5623.6 5654.2 5667.3 5688.6 5740.7 5753.1 5753.3 5285.7 5313.0 5315.5 5317.0 5321.3 5484.2 5611.4 5765.6 5806.2 5864.6 5492.8 5499.2 5537.2 5537.4 5608.7 5619.8 5636.3 5640.7 5640.9 5644.5 5384.7 5611.5 5618.7 5621.3 5625.1 5537.4 5556.9 5609.9 5614.5 5645.9 4383.9 4386.0 4429.3 4431.9 4443.9 3904.9 3911.6 3914.4 3915.9 3964.6 5053.6 5148.8 5194.3 5383.8 5429.8 5517.9 5574.1 5578.2 5627.2 5640.2 5219.5 5268.8 5269.2 5275.6 5285.7 4684.9 4777.7 4884.2 4890.1 4940.3 5723.8 5732.1 5739.8 5787.8 5860.7 5546.6 5594.1 5595.8 5603.6 5609.9 5568.0 5757.1 5782.1 5787.1 5796.9 5381.5 5392.4 5445.0 5447.9 5532.6 5519.0 5562.5 5569.5 5571.3 5586.3 5435.4 5437.5 5438.7 5445.3 5448.6 5290.7 5323.6 5332.9 5422.5 5426.2 5394.2 5412.3 5416.6 5417.2 5419.3 5504.3 5537.1 5538.3 5623.0 5625.6 5358.8 5372.8 5389.6 5399.2 5413.4 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | [#1] ext.html Cycles:180 Average:57532.39 Median:3614.02 stddev:113149.52 (3130.8%) stddev-sans-first:113386.18 11:35:01 INFO - PROCESS | 1188 | Values: 594.5 615.5 632.7 633.0 634.8 31339.4 41974.8 42604.0 42773.4 46695.0 44668.6 45043.3 45044.5 45069.0 45149.5 8695.3 8900.4 8903.3 8913.6 8914.5 7952.7 8137.1 10418.7 10580.2 10611.2 10703.5 10778.7 10789.7 10797.4 10800.8 10550.3 10642.1 10645.0 10668.6 10688.7 10459.2 10475.5 10507.2 10529.7 10531.3 41426.2 41531.6 41698.0 41796.2 41827.4 3603.1 3624.9 3680.5 3791.3 4041.2 31165.4 31573.7 31614.7 31707.4 31826.2 4149.7 4282.3 4356.3 4363.1 4463.9 321274.0 324134.0 326796.0 327838.0 328702.0 54639.5 55208.1 55385.0 55388.2 55508.2 56900.0 57169.7 57339.7 57445.0 57705.4 16239.5 16438.3 16576.5 16577.6 16581.9 11006.3 11193.7 11216.2 11308.5 11411.7 13887.2 14007.9 14045.4 15007.2 15488.2 5457.5 6117.6 6155.0 6257.4 6292.5 959.3 1009.6 1016.8 1125.4 1172.9 31278.4 31283.0 31390.8 31433.8 32011.8 1120.2 1140.9 1141.2 1161.3 1165.7 15076.0 16165.1 16282.3 16357.4 16692.0 471687.0 477295.0 480583.0 499421.0 552822.0 49994.3 50570.0 53217.0 53700.2 54174.5 1341.1 1341.9 1366.4 1366.9 1371.7 29620.6 31233.4 31576.0 31584.4 32132.5 47586.3 47978.0 47999.8 48010.3 48036.3 438787.0 439510.0 440601.0 441200.0 441438.0 26017.7 26799.6 26951.2 27331.2 27348.2 43273.6 43295.8 43688.8 45638.5 47388.3 35327.6 35952.8 36601.1 37119.8 37396.8 37895.8 38347.4 38568.8 39761.6 40320.8 38224.0 38339.0 38995.2 39129.8 39353.6 32683.7 34964.3 35342.0 35401.4 35647.5 25984.0 26273.3 26439.9 26498.9 26709.7 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | [#2] jquery.html Cycles:180 Average:25639.19 Median:20687.52 stddev:68563.82 (331.4%) stddev-sans-first:68729.91 11:35:01 INFO - PROCESS | 1188 | Values: 370.3 375.9 385.7 396.3 413.7 14126.5 14396.6 16907.0 19612.7 19674.3 18269.4 18486.8 18528.4 18668.0 18728.9 5397.1 5609.0 5610.7 5615.3 5618.5 4732.6 5117.4 5563.4 5583.2 5605.9 5570.3 5572.8 5574.4 5580.6 5583.9 5352.3 5516.5 5535.9 5543.1 5544.8 4789.2 4974.3 4982.6 4990.7 4991.1 20462.2 21268.9 22097.7 22572.5 22602.4 2430.4 2668.8 2870.9 2893.0 2911.7 9813.6 10028.1 10072.6 10663.7 10672.1 1718.6 1798.9 1888.2 1892.6 1944.4 77316.0 81045.6 82661.0 82802.6 84496.6 10462.5 10602.5 10660.6 10947.8 11185.2 14983.6 15102.1 15107.7 15164.2 15185.3 7641.5 7724.8 7973.2 7977.6 8108.4 7395.4 7461.2 7472.4 7687.0 7724.3 4579.3 4613.2 4622.1 4713.0 4831.3 1872.2 1971.6 1980.3 1995.6 1998.5 409.8 426.7 445.5 455.7 829.6 11316.7 11736.9 12287.1 12317.4 12384.8 449.6 454.1 455.7 463.8 480.0 4997.7 6263.2 6477.9 6521.6 6629.9 406394.0 408100.0 408276.0 419329.0 421070.0 54068.0 81228.0 93288.5 94697.5 95134.5 455.6 456.3 463.6 465.5 465.8 10722.3 10764.2 11008.3 11070.7 11129.8 38072.8 39108.4 40129.2 40482.0 40961.4 53916.5 55269.2 58237.0 58521.0 59178.0 7164.2 7256.4 7373.2 7404.0 7582.5 11838.1 12303.8 12529.8 12893.9 12946.2 10328.5 10752.7 10824.2 10901.8 11138.9 10080.4 10702.1 10875.8 10899.7 11166.3 10654.3 10667.5 10688.8 11024.4 11244.6 7450.9 7508.1 7581.3 7731.8 7790.6 9372.4 10543.1 10568.7 10574.3 11190.5 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | [#3] mootools.html Cycles:180 Average:5721.03 Median:4454.03 stddev:3323.70 (74.6%) stddev-sans-first:3316.75 11:35:01 INFO - PROCESS | 1188 | Values: 1344.2 1410.5 1442.0 1541.5 1731.7 7721.6 8660.7 8993.5 9005.0 9210.2 8940.6 9033.6 9034.1 9108.2 9113.7 2252.8 2284.2 2293.8 2296.5 2296.7 2166.0 2219.0 2223.6 2228.9 2229.5 2082.7 2093.0 2094.1 2106.1 2144.5 2205.8 2210.9 2211.4 2215.0 2220.0 2096.9 2126.2 2127.8 2129.6 2136.5 8968.7 8981.5 9017.1 9025.9 9062.2 5213.9 5409.6 5413.3 5415.1 5425.1 3425.5 3466.8 3480.2 3495.5 3532.1 1098.5 1105.3 1110.8 1127.2 1135.3 11344.0 11346.8 11441.1 11510.8 11521.3 4102.4 4221.3 4292.9 4311.8 4375.9 9403.1 9410.9 9425.3 9427.0 9430.3 4929.8 4969.1 4975.8 4975.9 4987.8 4038.6 4112.7 4532.1 4615.2 4637.9 3678.5 3728.7 3765.8 3785.8 3834.0 1119.3 1151.1 1165.8 1168.4 1176.5 903.3 906.6 926.2 939.0 952.8 5764.8 5961.7 6060.3 6103.4 6470.0 959.3 1004.1 1012.6 1013.0 1066.3 3900.7 4030.7 4051.3 4075.9 4208.8 11176.6 11367.6 11417.4 11493.3 11556.2 11280.0 11316.0 11322.0 11329.2 11357.7 8292.2 8354.6 8360.2 8368.7 8368.9 5772.5 5862.6 5867.4 5931.6 6017.5 10020.2 10150.1 10333.5 10344.3 10384.3 10406.6 10483.9 10513.7 10585.5 10589.0 6673.4 6830.1 7094.1 7653.2 7763.9 8407.4 8489.2 8514.0 8523.5 8530.5 7292.9 7461.5 7525.4 7669.7 7683.9 7403.6 7576.4 7579.4 7696.8 7901.8 6844.7 6947.5 6955.1 6959.6 6963.1 4582.2 4886.0 4908.9 4934.8 5043.4 4833.7 4913.3 4916.1 5039.5 5046.1 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | [#4] prototype.html Cycles:180 Average:4399.01 Median:4891.49 stddev:1253.66 (25.6%) stddev-sans-first:1219.06 11:35:01 INFO - PROCESS | 1188 | Values: 311.7 337.3 337.8 338.2 343.1 5027.9 5036.8 5176.0 5190.0 5259.5 5157.6 5158.3 5158.5 5162.1 5170.9 4093.3 4105.9 4107.8 4126.0 4144.7 4022.5 4069.7 4095.2 4139.3 4142.6 4064.8 4073.7 4080.0 4086.4 4098.3 4052.7 4062.7 4063.1 4076.9 4088.5 3622.3 3630.6 3631.2 3631.4 3649.8 4905.7 4999.3 5011.9 5045.9 5046.3 4561.5 4568.7 4570.5 4575.2 4576.7 5393.5 5405.4 5406.2 5416.3 5465.5 3724.0 3908.6 3912.6 3915.8 3963.8 5264.7 5451.6 5534.2 5566.8 5575.2 3901.2 3970.1 3992.2 3993.5 3999.9 4039.5 4043.7 4059.3 4060.8 4068.6 4604.2 4617.4 4620.8 4629.4 4633.7 5190.2 5395.4 5396.3 5402.5 5403.9 1388.8 1406.3 1406.7 1410.7 1415.6 862.8 899.9 908.9 912.3 923.6 4589.2 4614.3 4634.0 4642.5 4654.0 4848.1 4864.6 4869.2 4880.6 4929.6 4461.2 4622.8 4653.4 4694.8 4709.5 2568.7 2574.6 2578.4 2592.5 2618.3 4982.3 5004.9 5019.2 5025.6 5033.9 4741.7 4924.4 4974.5 4986.5 5020.0 4939.7 4951.8 4985.4 4990.5 5007.4 4863.5 4902.3 4923.3 4957.0 4966.8 4521.3 4792.4 4817.6 4845.4 4849.5 4955.5 5000.4 5444.4 5531.8 5593.3 3991.6 3992.8 3996.8 4013.8 4032.0 5588.3 5598.7 5607.5 5616.3 5617.4 5153.8 5332.1 5342.4 5360.7 5462.7 5357.8 5358.7 5371.9 5383.1 5405.3 5342.0 5343.9 5352.0 5404.8 5408.4 5166.6 5465.7 5480.2 5480.6 5484.8 5316.7 5327.4 5336.5 5337.6 5389.2 11:35:01 INFO - PROCESS | 1188 | 11:35:01 INFO - PROCESS | 1188 | [#5] yui.html Cycles:180 Average:19707.82 Median:50683.75 stddev:28457.48 (56.1%) stddev-sans-first:28501.29 11:35:01 INFO - PROCESS | 1188 | Values: 640.6 672.6 680.1 721.3 741.6 14563.8 15049.4 15553.3 15589.2 15610.0 14878.8 15164.8 15249.0 15270.8 15298.8 790.3 809.5 811.9 811.9 812.2 676.9 681.6 806.0 807.5 813.2 866.8 867.5 868.5 869.4 869.8 867.4 867.6 868.1 868.2 868.7 505.0 509.3 512.7 609.0 611.3 13805.9 13822.4 13875.4 13876.9 13885.2 13293.5 14224.3 14232.2 14256.1 14360.0 12513.7 12579.7 12585.1 12630.2 12663.4 4194.6 4267.8 4269.6 4304.5 4317.2 77660.2 77762.6 77784.8 77889.6 77900.0 9981.7 10057.9 10283.5 10364.3 10620.8 19824.6 20168.6 20170.0 20214.6 20265.5 8319.6 8390.7 8564.2 8608.5 8685.7 4314.0 4408.7 4580.3 4587.8 4679.7 3367.3 3472.4 3488.8 3488.9 3513.8 612.2 619.5 624.6 642.5 644.8 645.3 645.4 649.4 673.0 681.0 8304.5 8617.6 8693.2 8934.4 9216.4 572.1 646.9 665.5 666.8 675.6 4015.5 4292.6 4433.8 4446.7 4573.6 93283.5 99440.0 111627.4 122875.4 122892.0 89156.6 90330.6 94691.0 95601.0 95731.0 76780.6 80697.0 85194.0 85280.6 85979.0 8781.9 9223.6 9265.4 9270.3 9335.2 50184.0 50663.5 50704.0 51498.7 51571.5 49234.5 54533.5 63843.7 64324.4 69553.7 12359.8 12469.2 12565.7 12591.0 13184.4 13984.8 14059.8 14108.5 14141.2 14720.6 11651.4 11950.2 12369.9 12619.8 12906.1 12449.6 12456.4 13110.7 13135.7 13195.3 11284.5 11651.2 11671.6 11694.4 12024.3 9465.3 9804.3 9832.7 9915.5 10101.1 9774.2 10084.6 10298.9 10310.2 10413.5 11:35:01 INFO - PROCESS | 1188 | -------- Summary: end -------- 11:35:01 INFO - PROCESS | 1188 | 11:35:02 INFO - TEST-INFO | 1188: exit 0 11:35:02 INFO - TEST-OK | dromaeo_css | took 1117697ms 11:35:02 INFO - TEST-START | kraken 11:35:02 INFO - operating with platform_type : linux_ 11:35:02 INFO - Initialising browser for kraken test... 11:35:02 INFO - TEST-INFO | started process 1496 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp924xAR/profile http://localhost:50533/getInfo.html) 11:35:07 INFO - PROCESS | 1496 | __metrics Screen width/height:1600/1200 11:35:07 INFO - PROCESS | 1496 | colorDepth:24 11:35:07 INFO - PROCESS | 1496 | Browser inner width/height: 1024/697 11:35:07 INFO - PROCESS | 1496 | __metrics 11:35:07 INFO - PROCESS | 1496 | 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" 11:35:07 INFO - PROCESS | 1496 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:35:08 INFO - PROCESS | 1496 | console.error: PushService: 11:35:08 INFO - PROCESS | 1496 | stateChangeProcessEnqueue: Error transitioning state 11:35:08 INFO - PROCESS | 1496 | UnknownError 11:35:08 INFO - TEST-INFO | 1496: exit 0 11:35:08 INFO - Browser initialized. 11:35:08 INFO - Running cycle 1/1 for kraken test... 11:35:08 INFO - TEST-INFO | started process 1583 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp924xAR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:35:09 INFO - PROCESS | 1583 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 11:35:09 INFO - Terminating psutil.Process(pid=1583, name='firefox') 11:35:09 INFO - TEST-INFO | 1583: exit 127 11:35:09 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 11:35:09 ERROR - Traceback (most recent call last): 11:35:09 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 11:35:09 INFO - talos_results.add(mytest.runTest(browser_config, test)) 11:35:09 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 11:35:09 INFO - return self._runTest(browser_config, test_config, setup) 11:35:09 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 11:35:09 INFO - else None) 11:35:09 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 11:35:09 INFO - global_counters=self.global_counters 11:35:09 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 11:35:09 INFO - self.parse() 11:35:09 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 11:35:09 INFO - % self.report_tokens) 11:35:09 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 11:35:09 INFO - raise utils.TalosError(message) 11:35:09 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 11:35:09 INFO - TEST-INFO took 6478ms 11:35:09 INFO - SUITE-END | took 1124s 11:35:09 ERROR - Return code: 2 11:35:09 WARNING - setting return code to 2 11:35:09 ERROR - # TBPL FAILURE # 11:35:09 INFO - Running post-action listener: _resource_record_post_action 11:35:09 INFO - [mozharness: 2016-10-05 18:35:09.762978Z] Finished run-tests step (success) 11:35:09 INFO - Running post-run listener: _resource_record_post_run 11:35:09 INFO - Total resource usage - Wall time: 1142s; CPU: 13.0%; Read bytes: 0; Write bytes: 346402816; Read time: 0; Write time: 313016 11:35:09 INFO - TinderboxPrint: CPU usage
13.3% 11:35:09 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:35:09 INFO - TinderboxPrint: I/O write bytes / time
346,402,816 / 313,016 11:35:09 INFO - TinderboxPrint: CPU idle
7,903.6 (86.7%) 11:35:09 INFO - TinderboxPrint: CPU user
1,165.3 (12.8%) 11:35:09 INFO - TinderboxPrint: Swap in / out
0 / 0 11:35:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105705472; Read time: 0; Write time: 83352 11:35:09 INFO - run-tests - Wall time: 1125s; CPU: 13.0%; Read bytes: 0; Write bytes: 240222208; Read time: 0; Write time: 229640 11:35:10 INFO - Running post-run listener: _upload_blobber_files 11:35:10 INFO - Blob upload gear active. 11:35:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:35:10 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 11:35:10 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:35:10 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:35:10 INFO - (blobuploader) - INFO - Open directory for files ... 11:35:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:35:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:35:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:11 INFO - (blobuploader) - INFO - Done attempting. 11:35:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:35:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:35:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:12 INFO - (blobuploader) - INFO - Done attempting. 11:35:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:35:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:35:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:16 INFO - (blobuploader) - INFO - Done attempting. 11:35:16 INFO - (blobuploader) - INFO - Iteration through files over. 11:35:16 INFO - Return code: 0 11:35:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:35:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:35:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7850621427b223d6451cdbac00db02b5d9187878c1f471db50de7e87bddb77da50a5009c3f384527a6ab3a1495a24de6eaf901b355fefbbec059b07180261c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7933912f40a11460f8a94eb95a4767d7dc33a88ff1d0075b8f0bed5527d90f2abeef248da0d3d8137417f77135261c70fdc1f42be150e7166d112809be14f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d95fbad0bd7c27cf76bc15a4b49479eb5bd3e420aa6bf1bd2fd287a4519d998bb3239d69a7ed326a57a6904eed0ca732ed1740feb0b97ba5a4b7c5dc499a737"} 11:35:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:35:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:35:16 INFO - Contents: 11:35:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7850621427b223d6451cdbac00db02b5d9187878c1f471db50de7e87bddb77da50a5009c3f384527a6ab3a1495a24de6eaf901b355fefbbec059b07180261c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7933912f40a11460f8a94eb95a4767d7dc33a88ff1d0075b8f0bed5527d90f2abeef248da0d3d8137417f77135261c70fdc1f42be150e7166d112809be14f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d95fbad0bd7c27cf76bc15a4b49479eb5bd3e420aa6bf1bd2fd287a4519d998bb3239d69a7ed326a57a6904eed0ca732ed1740feb0b97ba5a4b7c5dc499a737"} 11:35:16 INFO - Running post-run listener: copy_logs_to_upload_dir 11:35:16 INFO - Copying logs to upload dir... 11:35:16 INFO - mkdir: /builds/slave/test/build/upload/logs 11:35:16 INFO - Copying logs to upload dir... 11:35:16 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1186.912086 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 46 secs) (at 2016-10-05 11:35:15.955350) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 11:35:15.958617) ========= 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=14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7850621427b223d6451cdbac00db02b5d9187878c1f471db50de7e87bddb77da50a5009c3f384527a6ab3a1495a24de6eaf901b355fefbbec059b07180261c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7933912f40a11460f8a94eb95a4767d7dc33a88ff1d0075b8f0bed5527d90f2abeef248da0d3d8137417f77135261c70fdc1f42be150e7166d112809be14f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d95fbad0bd7c27cf76bc15a4b49479eb5bd3e420aa6bf1bd2fd287a4519d998bb3239d69a7ed326a57a6904eed0ca732ed1740feb0b97ba5a4b7c5dc499a737"} build_url:https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/eEhuEf24Rv6tLWl66uQgog/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7850621427b223d6451cdbac00db02b5d9187878c1f471db50de7e87bddb77da50a5009c3f384527a6ab3a1495a24de6eaf901b355fefbbec059b07180261c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e7933912f40a11460f8a94eb95a4767d7dc33a88ff1d0075b8f0bed5527d90f2abeef248da0d3d8137417f77135261c70fdc1f42be150e7166d112809be14f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d95fbad0bd7c27cf76bc15a4b49479eb5bd3e420aa6bf1bd2fd287a4519d998bb3239d69a7ed326a57a6904eed0ca732ed1740feb0b97ba5a4b7c5dc499a737"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 11:35:15.984082) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:35:15.984465) ========= 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=14ef55db36f7623abb31b9cf000001d5-1475691326.917040-1975967506 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:35:16.071110) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 11:35:16.071480) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 11:35:16.071855) ========= ========= Total master_lag: 0.19 =========