builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1477406023.73 results: success (0) buildid: 20161025043002 builduid: 9706d9af7351482382edad9187abb9bf revision: c6ccd71126ff514bfc44b53e2217562e29a0cc38 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.733402) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.733859) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.734181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.787984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.788242) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-25 07:33:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-10-25 07:33:45 (43.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133361 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.943891) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.944203) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014979 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.994170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:43.994528) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c6ccd71126ff514bfc44b53e2217562e29a0cc38 --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 c6ccd71126ff514bfc44b53e2217562e29a0cc38 --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=516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-25 07:33:45,319 truncating revision to first 12 chars 2016-10-25 07:33:45,319 Setting DEBUG logging. 2016-10-25 07:33:45,319 attempt 1/10 2016-10-25 07:33:45,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c6ccd71126ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 07:33:45,764 unpacking tar archive at: mozilla-central-c6ccd71126ff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.756529 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:44.766758) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:44.767058) ========= script_repo_revision: c6ccd71126ff514bfc44b53e2217562e29a0cc38 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:44.767447) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:44.767744) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 07:33:44.781921) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-10-25 07:33:44.782190) ========= /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=516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:33:46 INFO - MultiFileLogger online at 20161025 07:33:46 in /builds/slave/test 07:33:46 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 07:33:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:33:46 INFO - {'append_to_log': False, 07:33:46 INFO - 'base_work_dir': '/builds/slave/test', 07:33:46 INFO - 'blob_upload_branch': 'Firefox', 07:33:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:33:46 INFO - 'branch': 'Firefox', 07:33:46 INFO - 'buildbot_json_path': 'buildprops.json', 07:33:46 INFO - 'config_files': ('talos/linux_config.py',), 07:33:46 INFO - 'default_actions': ('clobber', 07:33:46 INFO - 'read-buildbot-config', 07:33:46 INFO - 'download-and-extract', 07:33:46 INFO - 'populate-webroot', 07:33:46 INFO - 'create-virtualenv', 07:33:46 INFO - 'install', 07:33:46 INFO - 'run-tests'), 07:33:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:33:46 INFO - 'download_minidump_stackwalk': True, 07:33:46 INFO - 'download_symbols': 'ondemand', 07:33:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:33:46 INFO - 'tooltool.py': '/tools/tooltool.py', 07:33:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:33:46 INFO - '/tools/misc-python/virtualenv.py')}, 07:33:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:33:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:33:46 INFO - 'installer_path': 'installer.exe', 07:33:46 INFO - 'log_level': 'info', 07:33:46 INFO - 'log_name': 'talos', 07:33:46 INFO - 'log_to_console': True, 07:33:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:33:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:33:46 INFO - 'opt_config_files': (), 07:33:46 INFO - 'pip_index': False, 07:33:46 INFO - 'sps_profile': False, 07:33:46 INFO - 'sps_profile_interval': 0, 07:33:46 INFO - 'suite': 'dromaeojs', 07:33:46 INFO - 'system_bits': '32', 07:33:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:33:46 INFO - 'title': 'talos-linux64-ix-061', 07:33:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:33:46 INFO - 'use_talos_json': True, 07:33:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:33:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:33:46 INFO - 'work_dir': 'build'} 07:33:46 INFO - [mozharness: 2016-10-25 14:33:46.201073Z] Running clobber step. 07:33:46 INFO - Running pre-action listener: _resource_record_pre_action 07:33:46 INFO - Running main action method: clobber 07:33:46 INFO - rmtree: /builds/slave/test/build 07:33:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:33:46 INFO - Running post-action listener: _resource_record_post_action 07:33:46 INFO - [mozharness: 2016-10-25 14:33:46.661465Z] Finished clobber step (success) 07:33:46 INFO - [mozharness: 2016-10-25 14:33:46.661565Z] Running read-buildbot-config step. 07:33:46 INFO - Running pre-action listener: _resource_record_pre_action 07:33:46 INFO - Running main action method: read_buildbot_config 07:33:46 INFO - Using buildbot properties: 07:33:46 INFO - { 07:33:46 INFO - "project": "", 07:33:46 INFO - "product": "firefox", 07:33:46 INFO - "script_repo_revision": "production", 07:33:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:33:46 INFO - "repository": "", 07:33:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:33:46 INFO - "buildid": "20161025043002", 07:33:46 INFO - "pgo_build": "True", 07:33:46 INFO - "basedir": "/builds/slave/test", 07:33:46 INFO - "buildnumber": 33, 07:33:46 INFO - "slavename": "talos-linux64-ix-061", 07:33:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:33:46 INFO - "platform": "ubuntu64_hw", 07:33:46 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:33:46 INFO - "branch": "mozilla-central", 07:33:46 INFO - "repo_path": "mozilla-central", 07:33:46 INFO - "revision": "c6ccd71126ff514bfc44b53e2217562e29a0cc38", 07:33:46 INFO - "stage_platform": "linux64-pgo", 07:33:46 INFO - "builduid": "9706d9af7351482382edad9187abb9bf", 07:33:46 INFO - "slavebuilddir": "test" 07:33:46 INFO - } 07:33:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 07:33:46 INFO - Running post-action listener: _resource_record_post_action 07:33:46 INFO - [mozharness: 2016-10-25 14:33:46.664005Z] Finished read-buildbot-config step (success) 07:33:46 INFO - [mozharness: 2016-10-25 14:33:46.664094Z] Running download-and-extract step. 07:33:46 INFO - Running pre-action listener: _resource_record_pre_action 07:33:46 INFO - Running main action method: download_and_extract 07:33:46 INFO - mkdir: /builds/slave/test/build/tests 07:33: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')]} 07:33:46 INFO - trying https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:33:46 INFO - Downloading https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/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 07:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/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 07:33:47 INFO - Downloaded 1361 bytes. 07:33:47 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:33:47 INFO - Using the following test package requirements: 07:33:47 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 07:33:47 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:33:47 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:33:47 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'jsshell-linux-x86_64.zip'], 07:33:47 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:33:47 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 07:33:47 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:33:47 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:33:47 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:33:47 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:33:47 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:33:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 07:33:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:33:47 INFO - Fetch https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:33:47 INFO - Http code: 200 07:33:47 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 07:33:47 INFO - x-amz-cf-id: xjm6hgjH1JxAGZBgFx171QNvFNQM2zo7DhGLwajul3AhNQvRLL8tHA== 07:33:47 INFO - x-amz-version-id: jqAb9HV_X2xAfYMVOZSwletyDKJ7hw.X 07:33:48 INFO - Expected file size: 22036328 07:33:48 INFO - Obtained file size: 22036328 07:33:52 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 07:33:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 07:33:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:33:52 INFO - Fetch https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:33:53 INFO - Http code: 200 07:33:53 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 07:33:53 INFO - x-amz-cf-id: wwHmcHjlcyu_v5RBKDrfaoMIJLNyLACz9kUhpKlz05La5MWUsMjIrg== 07:33:53 INFO - x-amz-version-id: jqAb9HV_X2xAfYMVOZSwletyDKJ7hw.X 07:33:53 INFO - Expected file size: 22036328 07:33:53 INFO - Obtained file size: 22036328 07:33:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 07:33:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:33:58 INFO - Fetch https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:33:58 INFO - Http code: 200 07:33:58 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 07:33:58 INFO - x-amz-cf-id: mN_869UiZK9wkVJQaEuOv9sYA7H1_GtItHlpQbGIGS0YNhxPvFG4Vg== 07:33:58 INFO - x-amz-version-id: 30JulUYbiSJnITz0ZNlt66QXuB7hKYOQ 07:33:59 INFO - Expected file size: 14409028 07:33:59 INFO - Obtained file size: 14409028 07:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:00 INFO - trying https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/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 07:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/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 07:34:02 INFO - Downloaded 59298956 bytes. 07:34:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:34:02 INFO - mkdir: /builds/slave/test/properties 07:34:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:34:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:34:02 INFO - Contents: 07:34:02 INFO - build_url:https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:34:03 INFO - Running post-action listener: _resource_record_post_action 07:34:03 INFO - Running post-action listener: set_extra_try_arguments 07:34:03 INFO - [mozharness: 2016-10-25 14:34:03.505738Z] Finished download-and-extract step (success) 07:34:03 INFO - [mozharness: 2016-10-25 14:34:03.505966Z] Running populate-webroot step. 07:34:03 INFO - Running pre-action listener: _resource_record_pre_action 07:34:03 INFO - Running main action method: populate_webroot 07:34:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:34:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:34:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:34:03 INFO - '--tppagecycles', 07:34:03 INFO - '7'], 07:34:03 INFO - 'tests': ['tsvgm']}}, 07:34:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:34:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:34:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:34:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:34:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:34:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:34:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['damp', 'tps']}, 07:34:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'tests': ['damp', 'tps']}, 07:34:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['dromaeo_dom']}, 07:34:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:34:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['basic_compositor_video']}, 07:34:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:34:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['a11yr', 07:34:03 INFO - 'ts_paint', 07:34:03 INFO - 'tpaint', 07:34:03 INFO - 'sessionrestore', 07:34:03 INFO - 'sessionrestore_no_auto_restore', 07:34:03 INFO - 'tabpaint']}, 07:34:03 INFO - 'other-e10s': {'tests': ['a11yr', 07:34:03 INFO - 'ts_paint', 07:34:03 INFO - 'tpaint', 07:34:03 INFO - 'sessionrestore', 07:34:03 INFO - 'sessionrestore_no_auto_restore', 07:34:03 INFO - 'tabpaint']}, 07:34:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:34:03 INFO - 'ts_paint', 07:34:03 INFO - 'tpaint', 07:34:03 INFO - 'sessionrestore', 07:34:03 INFO - 'sessionrestore_no_auto_restore', 07:34:03 INFO - 'tabpaint']}, 07:34:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:34:03 INFO - 'ts_paint', 07:34:03 INFO - 'tpaint', 07:34:03 INFO - 'sessionrestore', 07:34:03 INFO - 'sessionrestore_no_auto_restore', 07:34:03 INFO - 'tabpaint']}, 07:34:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['a11yr', 07:34:03 INFO - 'ts_paint', 07:34:03 INFO - 'tpaint', 07:34:03 INFO - 'sessionrestore', 07:34:03 INFO - 'sessionrestore_no_auto_restore', 07:34:03 INFO - 'tabpaint']}, 07:34:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['a11yr', 07:34:03 INFO - 'ts_paint', 07:34:03 INFO - 'tpaint', 07:34:03 INFO - 'sessionrestore', 07:34:03 INFO - 'sessionrestore_no_auto_restore', 07:34:03 INFO - 'tabpaint']}, 07:34:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['tsvgx', 07:34:03 INFO - 'tsvgr_opacity', 07:34:03 INFO - 'tart', 07:34:03 INFO - 'tscrollx', 07:34:03 INFO - 'cart']}, 07:34:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:34:03 INFO - 'tsvgr_opacity', 07:34:03 INFO - 'tart', 07:34:03 INFO - 'tscrollx', 07:34:03 INFO - 'cart']}, 07:34:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'talos_options': ['--disable-e10s'], 07:34:03 INFO - 'tests': ['tp5o']}, 07:34:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'tests': ['tp5o']}, 07:34:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'talos_options': ['--disable-e10s', 07:34:03 INFO - '--xperf_path', 07:34:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:34:03 INFO - 'tests': ['tp5n']}, 07:34:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:34:03 INFO - 'talos_options': ['--xperf_path', 07:34:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:34:03 INFO - 'tests': ['tp5n']}}, 07:34:03 INFO - 'talos.zip': {'path': '', 07:34:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:34:03 INFO - Running post-action listener: _resource_record_post_action 07:34:03 INFO - [mozharness: 2016-10-25 14:34:03.523827Z] Finished populate-webroot step (success) 07:34:03 INFO - [mozharness: 2016-10-25 14:34:03.523916Z] Running create-virtualenv step. 07:34:03 INFO - Running pre-action listener: _resource_record_pre_action 07:34:03 INFO - Running main action method: create_virtualenv 07:34:03 INFO - Creating virtualenv /builds/slave/test/build/venv 07:34:03 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 07:34:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:34:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:34:03 INFO - Using real prefix '/usr' 07:34:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:34:04 INFO - Installing distribute.............................................................................................................................................................................................done. 07:34:06 INFO - Installing pip.................done. 07:34:06 INFO - Return code: 0 07:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:34:06 INFO - Reading from file tmpfile_stdout 07:34:06 INFO - Output received: 07:34:06 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) 07:34:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:34: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')]} 07:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34: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')]} 07:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:06 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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:06 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 07:34:06 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 07:34:06 INFO - Using env: {'DISPLAY': ':0', 07:34:06 INFO - 'HOME': '/home/cltbld', 07:34:06 INFO - 'LANG': 'en_US.UTF-8', 07:34:06 INFO - 'LANGUAGE': 'en_US:en', 07:34:06 INFO - 'LOGNAME': 'cltbld', 07:34:06 INFO - 'MAIL': '/var/mail/cltbld', 07:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:06 INFO - 'MOZ_NO_REMOTE': '1', 07:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:06 INFO - 'NO_EM_RESTART': '1', 07:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:06 INFO - 'PWD': '/builds/slave/test', 07:34:06 INFO - 'SHELL': '/bin/bash', 07:34:06 INFO - 'SHLVL': '1', 07:34:06 INFO - 'TERM': 'linux', 07:34:06 INFO - 'TMOUT': '86400', 07:34:06 INFO - 'USER': 'cltbld', 07:34:06 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:06 INFO - '_': '/tools/buildbot/bin/python'} 07:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:06 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 07:34:06 INFO - Cleaning up... 07:34:06 INFO - Return code: 0 07:34:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:34: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')]} 07:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34: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')]} 07:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:06 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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:06 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 07:34:06 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 07:34:06 INFO - Using env: {'DISPLAY': ':0', 07:34:06 INFO - 'HOME': '/home/cltbld', 07:34:06 INFO - 'LANG': 'en_US.UTF-8', 07:34:06 INFO - 'LANGUAGE': 'en_US:en', 07:34:06 INFO - 'LOGNAME': 'cltbld', 07:34:06 INFO - 'MAIL': '/var/mail/cltbld', 07:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:06 INFO - 'MOZ_NO_REMOTE': '1', 07:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:06 INFO - 'NO_EM_RESTART': '1', 07:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:06 INFO - 'PWD': '/builds/slave/test', 07:34:06 INFO - 'SHELL': '/bin/bash', 07:34:06 INFO - 'SHLVL': '1', 07:34:06 INFO - 'TERM': 'linux', 07:34:06 INFO - 'TMOUT': '86400', 07:34:06 INFO - 'USER': 'cltbld', 07:34:06 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:06 INFO - '_': '/tools/buildbot/bin/python'} 07:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:06 INFO - Downloading/unpacking psutil>=3.1.1 07:34:06 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 07:34:06 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 07:34:06 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 07:34:06 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 07:34:06 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 07:34:06 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 07:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:34:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:34:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:34:08 INFO - Installing collected packages: psutil 07:34:08 INFO - Running setup.py install for psutil 07:34:08 INFO - building 'psutil._psutil_linux' extension 07:34:08 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 07:34:09 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 07:34:09 INFO - building 'psutil._psutil_posix' extension 07:34:09 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 07:34:09 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 07:34:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:34:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:34:09 INFO - Successfully installed psutil 07:34:09 INFO - Cleaning up... 07:34:09 INFO - Return code: 0 07:34:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:34:09 INFO - Using env: {'DISPLAY': ':0', 07:34:09 INFO - 'HOME': '/home/cltbld', 07:34:09 INFO - 'LANG': 'en_US.UTF-8', 07:34:09 INFO - 'LANGUAGE': 'en_US:en', 07:34:09 INFO - 'LOGNAME': 'cltbld', 07:34:09 INFO - 'MAIL': '/var/mail/cltbld', 07:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:09 INFO - 'MOZ_NO_REMOTE': '1', 07:34:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:09 INFO - 'NO_EM_RESTART': '1', 07:34:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:09 INFO - 'PWD': '/builds/slave/test', 07:34:09 INFO - 'SHELL': '/bin/bash', 07:34:09 INFO - 'SHLVL': '1', 07:34:09 INFO - 'TERM': 'linux', 07:34:09 INFO - 'TMOUT': '86400', 07:34:09 INFO - 'USER': 'cltbld', 07:34:09 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:09 INFO - '_': '/tools/buildbot/bin/python'} 07:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:34:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:34:11 INFO - Installing collected packages: mozsystemmonitor 07:34:11 INFO - Running setup.py install for mozsystemmonitor 07:34:11 INFO - Successfully installed mozsystemmonitor 07:34:11 INFO - Cleaning up... 07:34:11 INFO - Return code: 0 07:34:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:34:11 INFO - Using env: {'DISPLAY': ':0', 07:34:11 INFO - 'HOME': '/home/cltbld', 07:34:11 INFO - 'LANG': 'en_US.UTF-8', 07:34:11 INFO - 'LANGUAGE': 'en_US:en', 07:34:11 INFO - 'LOGNAME': 'cltbld', 07:34:11 INFO - 'MAIL': '/var/mail/cltbld', 07:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:11 INFO - 'MOZ_NO_REMOTE': '1', 07:34:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:11 INFO - 'NO_EM_RESTART': '1', 07:34:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:11 INFO - 'PWD': '/builds/slave/test', 07:34:11 INFO - 'SHELL': '/bin/bash', 07:34:11 INFO - 'SHLVL': '1', 07:34:11 INFO - 'TERM': 'linux', 07:34:11 INFO - 'TMOUT': '86400', 07:34:11 INFO - 'USER': 'cltbld', 07:34:11 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:11 INFO - '_': '/tools/buildbot/bin/python'} 07:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:11 INFO - Downloading/unpacking jsonschema==2.5.1 07:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:34:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:14 INFO - Downloading functools32-3.2.3-2.tar.gz 07:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:34:14 INFO - warning: no files found matching '*.txt' 07:34:14 INFO - no previously-included directories found matching 'build' 07:34:14 INFO - no previously-included directories found matching 'dist' 07:34:14 INFO - no previously-included directories found matching '.git*' 07:34:14 INFO - Installing collected packages: jsonschema, functools32 07:34:14 INFO - Running setup.py install for functools32 07:34:14 INFO - warning: no files found matching '*.txt' 07:34:14 INFO - no previously-included directories found matching 'build' 07:34:14 INFO - no previously-included directories found matching 'dist' 07:34:14 INFO - no previously-included directories found matching '.git*' 07:34:14 INFO - Successfully installed jsonschema functools32 07:34:14 INFO - Cleaning up... 07:34:14 INFO - Return code: 0 07:34:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:34:14 INFO - Using env: {'DISPLAY': ':0', 07:34:14 INFO - 'HOME': '/home/cltbld', 07:34:14 INFO - 'LANG': 'en_US.UTF-8', 07:34:14 INFO - 'LANGUAGE': 'en_US:en', 07:34:14 INFO - 'LOGNAME': 'cltbld', 07:34:14 INFO - 'MAIL': '/var/mail/cltbld', 07:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:14 INFO - 'MOZ_NO_REMOTE': '1', 07:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:14 INFO - 'NO_EM_RESTART': '1', 07:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:14 INFO - 'PWD': '/builds/slave/test', 07:34:14 INFO - 'SHELL': '/bin/bash', 07:34:14 INFO - 'SHLVL': '1', 07:34:14 INFO - 'TERM': 'linux', 07:34:14 INFO - 'TMOUT': '86400', 07:34:14 INFO - 'USER': 'cltbld', 07:34:14 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:14 INFO - '_': '/tools/buildbot/bin/python'} 07:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:34:14 INFO - Cleaning up... 07:34:14 INFO - Return code: 0 07:34:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:34:14 INFO - Using env: {'DISPLAY': ':0', 07:34:14 INFO - 'HOME': '/home/cltbld', 07:34:14 INFO - 'LANG': 'en_US.UTF-8', 07:34:14 INFO - 'LANGUAGE': 'en_US:en', 07:34:14 INFO - 'LOGNAME': 'cltbld', 07:34:14 INFO - 'MAIL': '/var/mail/cltbld', 07:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:14 INFO - 'MOZ_NO_REMOTE': '1', 07:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:14 INFO - 'NO_EM_RESTART': '1', 07:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:14 INFO - 'PWD': '/builds/slave/test', 07:34:14 INFO - 'SHELL': '/bin/bash', 07:34:14 INFO - 'SHLVL': '1', 07:34:14 INFO - 'TERM': 'linux', 07:34:14 INFO - 'TMOUT': '86400', 07:34:14 INFO - 'USER': 'cltbld', 07:34:14 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:14 INFO - '_': '/tools/buildbot/bin/python'} 07:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:14 INFO - Downloading/unpacking blobuploader==1.2.4 07:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:16 INFO - Downloading blobuploader-1.2.4.tar.gz 07:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:34:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:34:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:17 INFO - Downloading docopt-0.6.1.tar.gz 07:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:34:17 INFO - Installing collected packages: blobuploader, requests, docopt 07:34:17 INFO - Running setup.py install for blobuploader 07:34:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:34:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:34:17 INFO - Running setup.py install for requests 07:34:17 INFO - Running setup.py install for docopt 07:34:18 INFO - Successfully installed blobuploader requests docopt 07:34:18 INFO - Cleaning up... 07:34:18 INFO - Return code: 0 07:34:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:18 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')]} 07:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:18 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')]} 07:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:18 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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:18 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 07:34:18 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 07:34:18 INFO - Using env: {'DISPLAY': ':0', 07:34:18 INFO - 'HOME': '/home/cltbld', 07:34:18 INFO - 'LANG': 'en_US.UTF-8', 07:34:18 INFO - 'LANGUAGE': 'en_US:en', 07:34:18 INFO - 'LOGNAME': 'cltbld', 07:34:18 INFO - 'MAIL': '/var/mail/cltbld', 07:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:18 INFO - 'MOZ_NO_REMOTE': '1', 07:34:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:18 INFO - 'NO_EM_RESTART': '1', 07:34:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:18 INFO - 'PWD': '/builds/slave/test', 07:34:18 INFO - 'SHELL': '/bin/bash', 07:34:18 INFO - 'SHLVL': '1', 07:34:18 INFO - 'TERM': 'linux', 07:34:18 INFO - 'TMOUT': '86400', 07:34:18 INFO - 'USER': 'cltbld', 07:34:18 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:18 INFO - '_': '/tools/buildbot/bin/python'} 07:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:18 INFO - Running setup.py (path:/tmp/pip-94xVV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:18 INFO - Running setup.py (path:/tmp/pip-CyDZu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:18 INFO - Running setup.py (path:/tmp/pip-dGixIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:18 INFO - Running setup.py (path:/tmp/pip-fyMsUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:18 INFO - Running setup.py (path:/tmp/pip-OQtKFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:18 INFO - Running setup.py (path:/tmp/pip-szOCPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:18 INFO - Running setup.py (path:/tmp/pip-Pz3D_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:18 INFO - Running setup.py (path:/tmp/pip-cLNTob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:18 INFO - Running setup.py (path:/tmp/pip-520GoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:19 INFO - Running setup.py (path:/tmp/pip-J3mlTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:19 INFO - Running setup.py (path:/tmp/pip-fsfqL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:19 INFO - Running setup.py (path:/tmp/pip-aTXIsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:19 INFO - Running setup.py (path:/tmp/pip-rqCpdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:19 INFO - Running setup.py (path:/tmp/pip-CDh5D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:19 INFO - Running setup.py (path:/tmp/pip-WSwlGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:19 INFO - Running setup.py (path:/tmp/pip-CGTuZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:19 INFO - Running setup.py (path:/tmp/pip-Kpn_wr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:34:19 INFO - Running setup.py install for manifestparser 07:34:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:34:19 INFO - Running setup.py install for mozcrash 07:34:19 INFO - Running setup.py install for mozdebug 07:34:19 INFO - Running setup.py install for mozdevice 07:34:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Running setup.py install for mozfile 07:34:20 INFO - Running setup.py install for mozhttpd 07:34:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Running setup.py install for mozinfo 07:34:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Running setup.py install for mozInstall 07:34:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Running setup.py install for mozleak 07:34:20 INFO - Running setup.py install for mozlog 07:34:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Running setup.py install for moznetwork 07:34:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Running setup.py install for mozprocess 07:34:20 INFO - Running setup.py install for mozprofile 07:34:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:34:20 INFO - Running setup.py install for mozrunner 07:34:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:34:21 INFO - Running setup.py install for mozscreenshot 07:34:21 INFO - Running setup.py install for moztest 07:34:21 INFO - Running setup.py install for mozversion 07:34:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:34:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:34:21 INFO - Cleaning up... 07:34:21 INFO - Return code: 0 07:34:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:21 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')]} 07:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:21 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')]} 07:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:21 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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:21 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 07:34:21 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 07:34:21 INFO - Using env: {'DISPLAY': ':0', 07:34:21 INFO - 'HOME': '/home/cltbld', 07:34:21 INFO - 'LANG': 'en_US.UTF-8', 07:34:21 INFO - 'LANGUAGE': 'en_US:en', 07:34:21 INFO - 'LOGNAME': 'cltbld', 07:34:21 INFO - 'MAIL': '/var/mail/cltbld', 07:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:21 INFO - 'MOZ_NO_REMOTE': '1', 07:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:21 INFO - 'NO_EM_RESTART': '1', 07:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:21 INFO - 'PWD': '/builds/slave/test', 07:34:21 INFO - 'SHELL': '/bin/bash', 07:34:21 INFO - 'SHLVL': '1', 07:34:21 INFO - 'TERM': 'linux', 07:34:21 INFO - 'TMOUT': '86400', 07:34:21 INFO - 'USER': 'cltbld', 07:34:21 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:21 INFO - '_': '/tools/buildbot/bin/python'} 07:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:21 INFO - Running setup.py (path:/tmp/pip-efFwgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:21 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)) 07:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:21 INFO - Running setup.py (path:/tmp/pip-Wvrckj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:21 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)) 07:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:21 INFO - Running setup.py (path:/tmp/pip-esy3xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:21 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)) 07:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:21 INFO - Running setup.py (path:/tmp/pip-fEltmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:21 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)) 07:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:21 INFO - Running setup.py (path:/tmp/pip-u51RBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:22 INFO - Running setup.py (path:/tmp/pip-xH2Orm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:22 INFO - Running setup.py (path:/tmp/pip-hGRGo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:22 INFO - Running setup.py (path:/tmp/pip-9cTmTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:22 INFO - Running setup.py (path:/tmp/pip-kJOWk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:22 INFO - Running setup.py (path:/tmp/pip-4x2hY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:22 INFO - Running setup.py (path:/tmp/pip-my8WW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:22 INFO - Running setup.py (path:/tmp/pip-bNIwN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:22 INFO - Running setup.py (path:/tmp/pip-ep3Ueo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:22 INFO - Running setup.py (path:/tmp/pip-ykjGU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:22 INFO - Running setup.py (path:/tmp/pip-sEkMz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:22 INFO - Running setup.py (path:/tmp/pip-dGNSvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:22 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)) 07:34:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:22 INFO - Running setup.py (path:/tmp/pip-IZM1kL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:22 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)) 07:34:22 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)) 07:34:22 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)) 07:34:22 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)) 07:34:22 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)) 07:34:22 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)) 07:34:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:24 INFO - Downloading blessings-1.6.tar.gz 07:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:34:24 INFO - Installing collected packages: blessings 07:34:24 INFO - Running setup.py install for blessings 07:34:25 INFO - Successfully installed blessings 07:34:25 INFO - Cleaning up... 07:34:25 INFO - Return code: 0 07:34:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:34:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:34:25 INFO - Reading from file tmpfile_stdout 07:34:25 INFO - Current package versions: 07:34:25 INFO - argparse == 1.2.1 07:34:25 INFO - blessings == 1.6 07:34:25 INFO - blobuploader == 1.2.4 07:34:25 INFO - docopt == 0.6.1 07:34:25 INFO - functools32 == 3.2.3-2 07:34:25 INFO - jsonschema == 2.5.1 07:34:25 INFO - manifestparser == 1.1 07:34:25 INFO - mozInstall == 1.12 07:34:25 INFO - mozcrash == 0.17 07:34:25 INFO - mozdebug == 0.1 07:34:25 INFO - mozdevice == 0.48 07:34:25 INFO - mozfile == 1.2 07:34:25 INFO - mozhttpd == 0.7 07:34:25 INFO - mozinfo == 0.9 07:34:25 INFO - mozleak == 0.1 07:34:25 INFO - mozlog == 3.4 07:34:25 INFO - moznetwork == 0.27 07:34:25 INFO - mozprocess == 0.23 07:34:25 INFO - mozprofile == 0.28 07:34:25 INFO - mozrunner == 6.12 07:34:25 INFO - mozscreenshot == 0.1 07:34:25 INFO - mozsystemmonitor == 0.3 07:34:25 INFO - moztest == 0.7 07:34:25 INFO - mozversion == 1.4 07:34:25 INFO - psutil == 3.1.1 07:34:25 INFO - requests == 1.2.3 07:34:25 INFO - wsgiref == 0.1.2 07:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:25 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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:25 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 07:34:25 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 07:34:25 INFO - Using env: {'DISPLAY': ':0', 07:34:25 INFO - 'HOME': '/home/cltbld', 07:34:25 INFO - 'LANG': 'en_US.UTF-8', 07:34:25 INFO - 'LANGUAGE': 'en_US:en', 07:34:25 INFO - 'LOGNAME': 'cltbld', 07:34:25 INFO - 'MAIL': '/var/mail/cltbld', 07:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:25 INFO - 'MOZ_NO_REMOTE': '1', 07:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:25 INFO - 'NO_EM_RESTART': '1', 07:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:25 INFO - 'PWD': '/builds/slave/test', 07:34:25 INFO - 'SHELL': '/bin/bash', 07:34:25 INFO - 'SHLVL': '1', 07:34:25 INFO - 'TERM': 'linux', 07:34:25 INFO - 'TMOUT': '86400', 07:34:25 INFO - 'USER': 'cltbld', 07:34:25 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:25 INFO - '_': '/tools/buildbot/bin/python'} 07:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 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)) 07:34:25 INFO - Cleaning up... 07:34:25 INFO - Return code: 0 07:34:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:25 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 0x7ffdc8ab7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f83790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', '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 07:34:25 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 07:34:25 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 07:34:25 INFO - Using env: {'DISPLAY': ':0', 07:34:25 INFO - 'HOME': '/home/cltbld', 07:34:25 INFO - 'LANG': 'en_US.UTF-8', 07:34:25 INFO - 'LANGUAGE': 'en_US:en', 07:34:25 INFO - 'LOGNAME': 'cltbld', 07:34:25 INFO - 'MAIL': '/var/mail/cltbld', 07:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:25 INFO - 'MOZ_NO_REMOTE': '1', 07:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:25 INFO - 'NO_EM_RESTART': '1', 07:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:25 INFO - 'PWD': '/builds/slave/test', 07:34:25 INFO - 'SHELL': '/bin/bash', 07:34:25 INFO - 'SHLVL': '1', 07:34:25 INFO - 'TERM': 'linux', 07:34:25 INFO - 'TMOUT': '86400', 07:34:25 INFO - 'USER': 'cltbld', 07:34:25 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:25 INFO - '_': '/tools/buildbot/bin/python'} 07:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:34:25 INFO - Cleaning up... 07:34:25 INFO - Return code: 0 07:34:25 INFO - Running post-action listener: _resource_record_post_action 07:34:25 INFO - Running post-action listener: _start_resource_monitoring 07:34:25 INFO - Starting resource monitoring. 07:34:25 INFO - [mozharness: 2016-10-25 14:34:25.790445Z] Finished create-virtualenv step (success) 07:34:25 INFO - [mozharness: 2016-10-25 14:34:25.790948Z] Running install step. 07:34:25 INFO - Running pre-action listener: _resource_record_pre_action 07:34:25 INFO - Running main action method: install 07:34:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:34:26 INFO - Reading from file tmpfile_stdout 07:34:26 INFO - Detecting whether we're running mozinstall >=1.0... 07:34:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:34:26 INFO - Reading from file tmpfile_stdout 07:34:26 INFO - Output received: 07:34:26 INFO - Usage: mozinstall [options] installer 07:34:26 INFO - Options: 07:34:26 INFO - -h, --help show this help message and exit 07:34:26 INFO - -d DEST, --destination=DEST 07:34:26 INFO - Directory to install application into. [default: 07:34:26 INFO - "/builds/slave/test"] 07:34:26 INFO - --app=APP Application being installed. [default: firefox] 07:34:26 INFO - mkdir: /builds/slave/test/build/application 07:34:26 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'] 07:34:26 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 07:34:42 INFO - Reading from file tmpfile_stdout 07:34:42 INFO - Output received: 07:34:42 INFO - /builds/slave/test/build/application/firefox/firefox 07:34:42 INFO - Running post-action listener: _resource_record_post_action 07:34:42 INFO - [mozharness: 2016-10-25 14:34:42.985888Z] Finished install step (success) 07:34:42 INFO - [mozharness: 2016-10-25 14:34:42.986133Z] Running run-tests step. 07:34:42 INFO - Running pre-action listener: _resource_record_pre_action 07:34:42 INFO - Running main action method: run_tests 07:34:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:34:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:34:42 INFO - Python 2.7.3 07:34:42 INFO - Return code: 0 07:34:42 INFO - grabbing minidump binary from tooltool 07:34: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')]} 07:34:42 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 0x1f80930>, '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 0x1f81830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f81cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:34:42 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 07:34:42 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 07:34:42 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 07:34:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:34:43 INFO - Return code: 0 07:34:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:34:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:34:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:34:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:34:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:34:43 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/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 07:34:43 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/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 07:34:43 INFO - Using env: {'DISPLAY': ':0', 07:34:43 INFO - 'HOME': '/home/cltbld', 07:34:43 INFO - 'LANG': 'en_US.UTF-8', 07:34:43 INFO - 'LANGUAGE': 'en_US:en', 07:34:43 INFO - 'LOGNAME': 'cltbld', 07:34:43 INFO - 'MAIL': '/var/mail/cltbld', 07:34:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:34:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:34:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:43 INFO - 'MOZ_NO_REMOTE': '1', 07:34:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:34:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:43 INFO - 'NO_EM_RESTART': '1', 07:34:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:43 INFO - 'PWD': '/builds/slave/test', 07:34:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:34:43 INFO - 'SHELL': '/bin/bash', 07:34:43 INFO - 'SHLVL': '1', 07:34:43 INFO - 'TERM': 'linux', 07:34:43 INFO - 'TMOUT': '86400', 07:34:43 INFO - 'USER': 'cltbld', 07:34:43 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195', 07:34:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:43 INFO - '_': '/tools/buildbot/bin/python'} 07:34:43 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/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 07:34:43 INFO - mozversion application_buildid: 20161025043002 07:34:43 INFO - mozversion application_changeset: c6ccd71126ff514bfc44b53e2217562e29a0cc38 07:34:43 INFO - mozversion application_display_name: Nightly 07:34:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:34:43 INFO - mozversion application_name: Firefox 07:34:43 INFO - mozversion application_remotingname: firefox 07:34:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:34:43 INFO - mozversion application_vendor: Mozilla 07:34:43 INFO - mozversion application_version: 52.0a1 07:34:43 INFO - mozversion platform_buildid: 20161025043002 07:34:43 INFO - mozversion platform_changeset: c6ccd71126ff514bfc44b53e2217562e29a0cc38 07:34:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:34:43 INFO - mozversion platform_version: 52.0a1 07:34:43 INFO - using testdate: 1477406083 07:34:43 INFO - actual date: 1477406083 07:34:43 INFO - starting webserver on 'localhost:56968' 07:34:43 INFO - SUITE-START | Running 2 tests 07:34:43 INFO - TEST-START | dromaeo_css 07:34:43 INFO - operating with platform_type : linux_ 07:34:43 INFO - Initialising browser for dromaeo_css test... 07:34:43 INFO - TEST-INFO | started process 23372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplXV4KJ/profile http://localhost:56968/getInfo.html) 07:34:47 INFO - PROCESS | 23372 | __metrics Screen width/height:1600/1200 07:34:47 INFO - PROCESS | 23372 | colorDepth:24 07:34:47 INFO - PROCESS | 23372 | Browser inner width/height: 1024/697 07:34:47 INFO - PROCESS | 23372 | __metrics 07:34:48 INFO - PROCESS | 23372 | 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" 07:34:48 INFO - PROCESS | 23372 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:34:48 INFO - PROCESS | 23372 | console.error: PushService: 07:34:48 INFO - PROCESS | 23372 | stateChangeProcessEnqueue: Error transitioning state 07:34:48 INFO - PROCESS | 23372 | UnknownError 07:34:49 INFO - TEST-INFO | 23372: exit 0 07:34:49 INFO - Browser initialized. 07:34:49 INFO - Running cycle 1/1 for dromaeo_css test... 07:34:49 INFO - TEST-INFO | started process 23456 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplXV4KJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:35:01 INFO - PROCESS | 23456 | RSS: Main: 172040192 07:35:01 INFO - PROCESS | 23456 | 07:35:53 INFO - PROCESS | 23456 | 1477406153230 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] 07:38:04 INFO - PROCESS | 23456 | Cycle 1(1): loaded http://localhost:56968/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56968/tests/dromaeo/cssquery-ext.html) 07:38:05 INFO - PROCESS | 23456 | RSS: Main: 292696064 07:38:05 INFO - PROCESS | 23456 | 07:41:08 INFO - PROCESS | 23456 | Cycle 1(1): loaded http://localhost:56968/tests/dromaeo/cssquery-ext.html (next: http://localhost:56968/tests/dromaeo/cssquery-jquery.html) 07:41:08 INFO - PROCESS | 23456 | RSS: Main: 274718720 07:41:08 INFO - PROCESS | 23456 | 07:44:11 INFO - PROCESS | 23456 | Cycle 1(1): loaded http://localhost:56968/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56968/tests/dromaeo/cssquery-mootools.html) 07:44:11 INFO - PROCESS | 23456 | RSS: Main: 277458944 07:44:11 INFO - PROCESS | 23456 | 07:47:13 INFO - PROCESS | 23456 | Cycle 1(1): loaded http://localhost:56968/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56968/tests/dromaeo/cssquery-prototype.html) 07:47:14 INFO - PROCESS | 23456 | RSS: Main: 243146752 07:47:14 INFO - PROCESS | 23456 | 07:50:17 INFO - PROCESS | 23456 | Cycle 1(1): loaded http://localhost:56968/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56968/tests/dromaeo/cssquery-yui.html) 07:50:17 INFO - PROCESS | 23456 | RSS: Main: 212905984 07:50:17 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | Cycle 1(1): loaded http://localhost:56968/tests/dromaeo/cssquery-yui.html (next: http://localhost:56968/tests/dromaeo/cssquery-dojo.html) 07:53:20 INFO - PROCESS | 23456 | RSS: Main: 306593792 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | __start_tp_report 07:53:20 INFO - PROCESS | 23456 | _x_x_mozilla_page_load 07:53:20 INFO - PROCESS | 23456 | _x_x_mozilla_page_load_details 07:53:20 INFO - PROCESS | 23456 | |i|pagename|runs| 07:53:20 INFO - PROCESS | 23456 | |0;dojo.html;3043.223977885639;3127.265092703216;3143.732782713469;3157.4316623007862;3158.210447388153;5089.796408143679;5164.767585458654;5292.444293349201;5356.866078348044;5393.217983392406;5204.687718736878;5294.814681486148;5377.381601115872;5389.946100538993;5430.809921652743;4797.208460604013;4807.18277892759;4865.951340486603;4889.413270407556;4899.436564795059;4899.8775030624165;4913.238448040559;4940.110780059588;4955.925661115069;5020.246962955549;4855.441624213226;4882.780274887621;4898.02039592083;4901.68139070961;4951.3810773653295;4736.029114031615;4778.737169455679;4785.760711964388;4786.928196077061;4841.152798260326;4785.736784476852;4826.758662066918;4831.734254615994;4882.170031094723;4907.043126590316;5338.97330513345;5352.892942141136;5367.382750983609;5380.946190538123;5381.165919282517;5517.724113794295;5571.609987300891;5594.021046316919;5716.42835716425;5734.082546792493;5693.259876216107;5730.627509211888;5733.684647344395;5746.856328591777;5748.252727145445;5340.385855626614;5341.813036543703;5343.091674415358;5344.839654810362;5348.438413966516;5792.015357389254;5792.751332512344;5803.216565763652;5805.100209467455;5865.853353666107;5380.7309634518115;5486.067368547357;5487.478689524569;5513.58648101394;5578.553715702815;5658.773649053992;5678.4605462480995;5679.744411501492;5680.034394153004;5751.597388182787;5660.745266463019;5662.405447428043;5666.2350582670815;5669;5670.432956704379;5486.1222204447095;5520.475554822286;5523.7238138092935;5625.4374562544235;5744.741486633112;4206.158768246363;4335.869923902288;4379.934300985487;4416.094700586372;4433.623142032899;3904.121572646132;3906.941395879064;3908.627181286062;3949.3681011038097;3965.306071437487;5410.188471729272;5412.377576578665;5414.864628384243;5416.945830541644;5417.4582541746295;5478.041342765;5487.286652735119;5537.224788529609;5622.156676498476;5706.600537962303;5312.62811603184;5368.060589396918;5414.3502779665905;5431.103867861759;5438.15708565173;4859.659823812299;4859.902801944012;4867.537583929521;4889.584385327287;4913.975430122898;5910.852228694317;5913.231279933667;5918.082697181943;5919.378465261086;5924.644521328648;5491.341039075336;5555.611107222618;5564.666120032811;5631.521320687787;5649.548036157015;5808.186853840551;5819.883602328015;5820.097884827858;5822.912656310074;5836.066229403275;5479.506844384025;5511.311086114236;5515;5596.356419011703;5603.2155498231095;5619.662820230799;5625.043742563692;5630.718464076698;5683.403242659461;5692.829215123553;5524.447555244444;5527.861803454945;5536.806211782568;5537.501624853621;5538.584606154474;5365.3293338332705;5416.51251387377;5520.91718624229;5532.6680399176175;5596.076647353141;5397.163439666858;5400.9459905400445;5406.621536492407;5465.344158700822;5545.057340252086;5459.181122831607;5476.2607048048;5477.917831232455;5480.890382192413;5503.367112782082;5341.41244463101;5358.571314294769;5383.219433182245;5389.164679474688;5450.563954883521 07:53:20 INFO - PROCESS | 23456 | |1;ext.html;556.6459204092305;578.228065532514;607.0136028952951;633.9429451349375;645.519088279232;31298.68701312986;43754.343684844716;44115.55884441155;44163.337549936776;44202.778986105106;45255.32117018248;45500.77249613747;45552.3167152493;45573.54426455743;45646;8064.27421532062;8935.955320223406;8979.595918183697;9088.363814532986;9089.727308180733;7853.8821917671285;10607.098396636295;10826.67519974398;10829.83755243672;11204.047655949213;10943.56225750969;11005.339679619248;11017.669469915916;11017.944910275437;11021.063209627191;8066.838663226703;8240.8351832963;10947.34315941042;11023.503942322615;11034.337939723602;10299.845502317472;10633.255672102956;10653.201009924287;10962.177836662282;10970.890291097066;41709;41727.165456691;42023;42058;42123.789381053204;3214.2928555717704;3378.2061215614312;3391.372596069735;3405.9659403405894;3810.123671555558;30903.2274193147;31276;31813.204669883205;31815;32416.675833241836;4329.458817647794;4372.191144638252;4433.091216300651;4531.365608814735;4644.349791029259;302431.7811396963;303582.9999999978;303952;304064;304884;56091;56322.43677563254;56504.15243771347;56509;56531;31874.20314492156;32369;32410.675893240765;32422;32446.675533244364;15247.23763811805;15283.38866445352;15377.077375357514;15390.923045384701;15403.22983850803;10676.412797296069;10958.123350131971;10989.06592939593;11438.142139339583;11637.709057273636;17745.911270443565;18621.255149794128;18659.533511662317;18677.719834202497;18699.252029918924;6798.422134118655;6817.318268173143;7018.508704390746;7105.43156547475;7166.53417527859;896.4710820615868;994.3735446668552;1013.8327176016022;1101.3391964821044;1107.4850194659523;30870;31380.37239255248;31733.8413307932;31800.840995795337;32381.676183237865;1019.5564929255797;1073.640330489295;1077.0666653357243;1091.0235339371218;1150.9079273658087;15461.9226903867;16308.836911630731;16454.344533015377;16558.586035349213;16559.503214903572;533776;551587;552167;553039;553325;56736.716316418155;57169;57405.13892291619;57442.425575743706;57529.71235143798;1213.6055781870928;1293.2693028438903;1309.214471317202;1344.3211178354868;1390.6245313765228;30617.23456913595;32483.512747308338;32858.34283314368;33310.66689333076;33990.49014264739;47643.285350719074;48425.757871210415;48489.27266090941;48498.75750621224;48503;385010;387279;387342;388057;389968;40829.79585102056;41393.79303103465;41448.79275603723;41522.169556609304;44961.77519112384;45829.08341833078;45924.54075459336;46463.76768116138;46597.30104048374;47882.521174787806;38838;39206.21587568176;39215.2156956865;39230.80384598059;40694.79652601718;38223.23553528969;38696.80651596724;38868.61131388763;39183;39363.80318098506;38945.2210955785;39760;39796.60203397929;40145.39781903332;40176.397354040295;30452.39095218039;33835.15412114717;34186.99288478342;35017.474737879464;35056.298874021864;28036.57945130784;28129;28151.43697126087;28202.576961345185;28316.433671326045 07:53:20 INFO - PROCESS | 23456 | |2;jquery.html;356.3923510414743;372.46179270953473;375.75575875680806;382.9502164718586;408.37314721901885;14128.364223609937;17942.91028544857;18238.817611823895;18327.908360458212;19541.120649570767;16631.835771496;17655.20551575179;18182.272709091652;18334.358297459592;18600.069996500155;5109.897802043966;5335.5198032177095;5339.9733001334935;5342.839714808562;5343.145096784515;3817.141143242776;4126.5254495732925;5238.292830467876;5317.494837990384;5317.973410132944;4087.7751723655188;6470.320616335288;6474.546781725281;6478.2226132864125;6480.611163330215;5827.970860145672;5875.441833025856;6140.9385906140815;6455.806325810209;6460.935390646104;5655.293088363955;5657.32112146545;5658.292713410824;5659.189059500962;5661.915071273934;13587.728245435135;15589.376424942988;21335.78664213369;22149.557008859894;22159.889200554055;2013.536886516095;2117.502386939068;2164.177612507253;2264.9128418359114;2780.4439112177442;9313.254939604816;9587.328886977913;10018.549165287579;10108.64619738313;10645.148388129008;1849.6855534559156;1893.1007771308655;1927.5855691026356;1965.4496740912568;1985.1860737097866;74566.62716686382;80625;81522.59238703828;82185.17814821895;84442.57778711067;9296.488693121808;9428.717138485856;9827.803443931225;9906.851397229048;9910.504474776233;13060;13108.410121544553;13131;13132;13142;7115.324044215791;7369.494371913575;7422.591757453392;7561.6597253122645;7571.545707257582;7266.309700578437;7316.524425912405;7350.228226036189;7466;7542.963399882001;3616.150204701893;3807.9619203807606;4071.531773846046;4097.590240975938;4147.274227010261;1932.6614796096599;2026.7061276115176;2027.2701827342146;2041.836653067751;2059.324106469944;856.7130011446235;926.8285367207088;929.2240978782792;947.4268067818871;975.3708857786829;18426.262949482138;19352.806471935382;19534;19865.702014469796;19926.70109948352;883.7216276872764;933.2533972821716;940.0129863643148;942.8026406463753;952.9809403811885;10331.193274290348;10576.990371829328;10760.246782725291;10800.297980631234;10828.025477706886;453112;466526;470939;473011;482273;51942.22086668627;75769.62115189499;86417.56791216004;88084;88992.5550372244;953.6328513522344;980.789130336981;991.5686676295834;1003.3327836988467;1013.4628646817051;15353.462628807938;16852.494425167264;17355.30578776835;17418.867773594677;17470.30118795234;40616.39075413812;41439.37840932449;42018.789906050275;42079.57920420879;42334;51899;53123.20315195198;53917.19124213061;55051.17423238575;55473;8327.458715183493;8339.45793523419;8396.496210227406;8580.05619381855;8665.263452606347;12479.069093235601;12889;13048;13185.340732963505;13309.66725831862;11120.777584448428;11385.316880987168;11393.658190254306;11613.477393517482;11768.587769467718;10950.835737463785;11137.777244455228;11266.436678166223;11431.771364572829;11831.46758395857;11657.300561966309;11720.589779357682;11911.761764764482;12066.638000859988;12125.393730313273;8415.495070295801;8490.278326342082;8587.828243434971;8627.266682331821;8681.305495560466;9866.260030497884;11472.254303470248;11529.711757206136;11616.88383116158;11863.76272474563 07:53:20 INFO - PROCESS | 23456 | |3;mootools.html;1307.1895424836591;1430.3348942741627;1442.3869855311493;1568.3491351089299;1727.4731206981864;7435.516691415054;8238.794030149242;8444.028936672277;8514.191151840565;8838.823223535526;8585.957070214656;8651.134886511361;8661.393702440831;8665.653373865038;8666.739997800076;2235.597592433365;2251.954960900781;2260.762619924909;2263.875486848223;2266.4900397410615;2174.586828502587;2228.4651683595903;2228.55428914217;2234.6759719840616;2235.0724449353497;2173.3154056472163;2177.7495588007346;2180.498485348363;2185.071344678513;2221.7444993825757;2150.161437039556;2168.5229249565073;2173.467500462389;2173.923912663065;2174.7390313162364;1995;1995.7006449032617;1996.331229038274;1997.5505511259996;2000.849936254772;8122.959385203036;8240.546769927652;8242.629081691339;8250.958745206295;8445.155484451567;4792.353032340659;4991.251312303148;4995.125852975751;5002.52476014778;5011.974940125312;3278.7868788528667;3297.7856439331367;3333.8166400847945;3366.9663303367142;3377.408953433164;1030.5568605499632;1045.4250162410644;1048.6591857646295;1049.574922156528;1100.7633358827811;11832.408379580986;11946.522139114424;12012.339321337324;12024.278543287342;12048.45781939806;4016.0160760613253;4199.202151591188;4310.137972405531;4392.626626736763;4409.0520538084465;9451.858222126673;9457.337986341028;9463.526823658927;9466.668666596635;9477.336586438883;4844.854654360375;4845.297431872359;4904.4605093439695;4905.852824415273;4911.115999120121;3827.5789663137034;3909.944315034925;4245.1085272093205;4541.205289074399;4544.97727511367;3451.5685539307588;3514.9121271967892;3563.2873425315042;3564.8395822188063;3587.0673624857204;1019.5666841592442;1024.5031159887449;1026.1944373666636;1030.4023666273692;1032.3496197395593;857.6697971280977;944.7921457279525;948.1561410344799;966.6085235479642;966.8743063401713;5644.548436125182;5648.15277708347;5848.210491583667;5849.356570777211;5905.763769449174;428.4237700293101;451.1563376485992;451.92091384007665;452.3170013279971;453.9614132798683;3669.798161101112;3755.117547376364;3790.3177428063213;3803.904902377463;3926.391409331558;10546.103581195532;10809.67570972872;10892.40091794959;10901.127909767358;10903.890961090447;9505.857412138823;9915.454649994319;10097.495125243708;10755.784884302426;10794.946025269981;8043.19568043191;8048.959755201186;8200.220979006985;8211.671533138728;8214.63034163464;5849.268841394825;6003.579749417673;6025.8192254232445;6034.9000254489465;6050.818475445743;9681.757956051155;9748.805023899624;9753.170980466739;9863.704088877344;10005.04952029556;8601.870971935552;9853.556589953327;9975.401475911469;10018.448985305879;10066.345687530287;6637.402633762985;7420.628968551443;7543.73596924105;7608.353289970414;7815.179406162499;8123.4313598047565;8154.103048664523;8207.753767386988;8282.83434331298;8328.042275138194;6901.792946211622;7008.474364422586;7384.889226661497;7398.519096258726;7399.223081576357;7165.426765858823;7165.928340716735;7222.602756848424;7299.087614048244;7362.447816413897;6781.220159681699;6800.387965083166;6831.89752153728;6854.383105520527;6876.862462750817;4810.63920205993;4842.927356089591;4858.805647774049;4935.235038569028;5114.539691427789;4864.294677271847;4917.483664215349;4943.456219815889;5011.223260394645;5101.744912754421 07:53:20 INFO - PROCESS | 23456 | |4;prototype.html;318.89157686386625;324.3610088126391;326.9182704323919;336.8534687410978;336.90398236502597;5307.973460132699;5331.706756128411;5346.064438723222;5346.893062138755;5350.384705758839;4994.7003179809235;5051.131081499851;5134.64057515974;5184.403793563742;5302.867428314294;4134.689898257643;4136.5036195656485;4138.317177665682;4163.5836416358425;4181.832726690929;4166.479190101238;4166.937495937548;4191.443219636019;4216.97891510542;4217.746935183899;3778.9811050944845;3922.9803850980707;4026.6376026157786;4136;4157.854475093357;4124.484439445069;4126.133511962492;4133.338665813486;4134;4135.0565234339965;3681.7975011374365;3687.631236876318;3690.8004898408126;3691.870784522529;3695.796731179784;5090.821821236276;5112.156494178456;5114.820981265638;5220.112580861262;5240.004399164185;4743.739094349809;4749.4775574686755;4757.1437141314545;4800;4801.303810947393;5522.6134170608075;5528.668279903179;5538.363088244822;5556.388797232301;5602.523785478239;3914.6476817086314;4060.634542891154;4090.6931980101613;4096.836126554934;4109.732867363612;5664.716764161775;5667.364675425947;5672.773089076432;5682.7442765075675;5705.85882823437;4133.441985331941;4134.607212314825;4137.234611596864;4175.791210439465;4177.436046133794;3869.187470631143;4103.8974025649;4233.7883105844585;4235.7670328131635;4242.151569686075;4738.2468486660155;4743.66794324394;4750.406199225097;4756.381670382897;4782.904341913142;5702.372738998707;5708.143778433268;5708.71456427177;5715.399883012224;5716.857078573069;1358.2665360705307;1372.8901687864948;1418.5460652591278;1428.6285565752978;1431.4488921765196;909.4134283387178;910.8022949820964;926.5598840550684;931.8602209668604;935.7660584853786;4695.976520117378;4736.407949006374;4744.169770290186;4776.47458779534;4807.975960120247;4961.461946796505;4967.78933374652;5018.397792264878;5080.23796430531;5085.949140508622;4534.6372290216705;4725.976370118127;4733.83431579893;4734.834280800155;4775.403074615673;2650.787936965038;2652.2441104285185;2659.614355918381;2666.2667766364407;2680.463907218564;5103.897922041612;5112.5143111405105;5168;5173.224016397495;5191.896162076738;4813.446453657874;4969.403671559364;5019.899602007939;5063.822766203239;5141.511556402136;5062.620303477254;5095.337606111182;5108.288723277669;5132.101882170605;5211.47885211491;4983.327250821093;5023.09584274834;5026.321446604661;5073.467285934924;5076.923846142385;4923;4959.256111583291;5039.899202016012;5284.497972692588;5740.225069615549;5827.609461248413;5866.149408335682;5918.704064796657;5919.4968427684125;5940.702964851654;4034.7982600870428;4113.958860411358;4137.110521237948;4149.33610622299;4150.481189851269;5422.728863556727;5568.916466252928;5713.771449142154;5754.798582049608;5802.216700745345;5509.889802204014;5587.161925711176;5587.720613969366;5593.300837395325;5601.943980560142;5365.248865158803;5594.440555944373;5620.690862002629;5649.4633009864;5693.4875861172695;5571.470710282517;5576.470235327637;5585.664860108406;5592.902661501937;5599.020171470058;5626.549876009991;5628.324601047901;5689.317281926195;5730.5702072345575;5735.254416925773;5264.605154613343;5513.807016754395;5531.419200983839;5535.806246781504;5585.441455854382 07:53:20 INFO - PROCESS | 23456 | |5;yui.html;622.3030206169092;646.2019406033547;712.768350286157;741.6217728958233;821.7165078048072;12253.816192757105;13204.207747535154;13236.53672121476;13260.53588124416;13270.270135142564;12271.570495032676;12353.444095015724;12372;12377.071719621019;12391.938040309786;653.2291895563258;663.8539521305305;784.3881772217679;807.1081454992237;810.7243537197352;775.8564665536894;796.8087658961837;800.7877912353202;805.621357961757;806.0327606871748;845.8012367093759;847.7922908887331;848.6308455821707;848.6393282854793;849.6219182463806;691.1360799001249;846.3060290561742;846.3609974469298;846.5259454705384;847.5338563789921;515.7627491353982;600.2197143713155;611.5994023914343;613.9754409823602;616.9352218017088;12101.878981210164;12134.757304853854;12150.33173175475;12181.451834667461;12190.57332993341;11974.940125299318;12070.939645301805;12086.879131208663;12462.626121216377;12626;11912.106592005634;12050.102427864405;12119.575814846527;12150.939245303805;12170.574029909;4297.78511074445;4306.224879521685;4329.458817647794;4339.631131353839;4339.934900976488;60677;60920.69539652318;61510.384896150914;63543;64088;9681.66114186;9730.854037189449;9749.317547771589;9943.25425593069;10126.240531959986;17049.914750426167;17199.91400042992;17276.65446691059;17282.65434691299;17285.135743212788;7352.117745870422;7379.298966598165;7692.076950765832;7741;7809.336206422404;4311.76285304305;4410.867673969786;4468.151051300242;4484.349769283435;4573.702709323883;3077.1537827097727;3172.159377764894;3174.555562221291;3174.619045714529;3175.539546765706;538.0557122250425;542.6581253810075;559.6819490100754;561.6012631031931;566.5184593095836;538.5641583082183;583.9649621022752;599.3048064245454;646.3923911523153;651.0527182948798;7819.218078192135;7921.643526041342;8086.312663423675;8125.95937020323;8133.268981027604;518.284767021508;522.3809785404308;556.3212880286045;596.2517041113375;597.1401182297478;3714.665680088805;3827.349350610403;3837.6929845612285;3872.1390517574882;3988.760674359548;50701;50809.237861432106;50878.74560627173;50958.235626465634;73896;44970.77514612472;45251.77374113108;46268.768656157175;61433.692831535554;61960.69019654873;56241.43758562444;56557.434425656036;59106.11340829891;59107;61307.693461532406;7780.49426787257;8327.541985190872;8723.214910658071;8735.213830755263;8964.68623598171;41141.382879256234;41536.35691889754;41669.37495937624;42334.57665423306;44961.32558011567;53974.460255396945;54868;55485;57067.42932570621;61132.69433652803;9990.700278991642;10221.131203847755;10396.948015259875;10505.422201778976;11411;12221.144519883523;12294.631161065183;12516.374181290717;12661.303628300531;13020.804687929878;10201.744956375853;11490.195686301879;11558.793830557981;11838.704032399259;12265.202761820454;11062.502187401748;11532.827007594726;11624.649161727244;11632.651020469399;11859.940700296444;11243.7751244973;11384.14618903569;11387.145964052896;11419.543218271509;11777.764444710887;9434.339596228197;9573.808523829344;9659.758506037406;9884.851727223953;10214.489275536045;8987.640494380152;9197.264218862341;9206.313070074255;9363.906360936304;9522.428654280766 07:53:20 INFO - PROCESS | 23456 | __end_tp_report 07:53:20 INFO - PROCESS | 23456 | __start_cc_report 07:53:20 INFO - PROCESS | 23456 | _x_x_mozilla_cycle_collect,1131 07:53:20 INFO - PROCESS | 23456 | __end_cc_report 07:53:20 INFO - PROCESS | 23456 | __startTimestamp1477407200741__endTimestamp 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | ------- Summary: start ------- 07:53:20 INFO - PROCESS | 23456 | Number of tests: 6 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | [#0] dojo.html Cycles:165 Average:5264.33 Median:5430.81 stddev:573.92 (10.6%) stddev-sans-first:548.59 07:53:20 INFO - PROCESS | 23456 | Values: 3043.2 3127.3 3143.7 3157.4 3158.2 5089.8 5164.8 5292.4 5356.9 5393.2 5204.7 5294.8 5377.4 5389.9 5430.8 4797.2 4807.2 4866.0 4889.4 4899.4 4899.9 4913.2 4940.1 4955.9 5020.2 4855.4 4882.8 4898.0 4901.7 4951.4 4736.0 4778.7 4785.8 4786.9 4841.2 4785.7 4826.8 4831.7 4882.2 4907.0 5339.0 5352.9 5367.4 5380.9 5381.2 5517.7 5571.6 5594.0 5716.4 5734.1 5693.3 5730.6 5733.7 5746.9 5748.3 5340.4 5341.8 5343.1 5344.8 5348.4 5792.0 5792.8 5803.2 5805.1 5865.9 5380.7 5486.1 5487.5 5513.6 5578.6 5658.8 5678.5 5679.7 5680.0 5751.6 5660.7 5662.4 5666.2 5669.0 5670.4 5486.1 5520.5 5523.7 5625.4 5744.7 4206.2 4335.9 4379.9 4416.1 4433.6 3904.1 3906.9 3908.6 3949.4 3965.3 5410.2 5412.4 5414.9 5416.9 5417.5 5478.0 5487.3 5537.2 5622.2 5706.6 5312.6 5368.1 5414.4 5431.1 5438.2 4859.7 4859.9 4867.5 4889.6 4914.0 5910.9 5913.2 5918.1 5919.4 5924.6 5491.3 5555.6 5564.7 5631.5 5649.5 5808.2 5819.9 5820.1 5822.9 5836.1 5479.5 5511.3 5515.0 5596.4 5603.2 5619.7 5625.0 5630.7 5683.4 5692.8 5524.4 5527.9 5536.8 5537.5 5538.6 5365.3 5416.5 5520.9 5532.7 5596.1 5397.2 5400.9 5406.6 5465.3 5545.1 5459.2 5476.3 5477.9 5480.9 5503.4 5341.4 5358.6 5383.2 5389.2 5450.6 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | [#1] ext.html Cycles:180 Average:57181.69 Median:21016.68 stddev:112927.31 (537.3%) stddev-sans-first:113164.07 07:53:20 INFO - PROCESS | 23456 | Values: 556.6 578.2 607.0 633.9 645.5 31298.7 43754.3 44115.6 44163.3 44202.8 45255.3 45500.8 45552.3 45573.5 45646.0 8064.3 8936.0 8979.6 9088.4 9089.7 7853.9 10607.1 10826.7 10829.8 11204.0 10943.6 11005.3 11017.7 11017.9 11021.1 8066.8 8240.8 10947.3 11023.5 11034.3 10299.8 10633.3 10653.2 10962.2 10970.9 41709.0 41727.2 42023.0 42058.0 42123.8 3214.3 3378.2 3391.4 3406.0 3810.1 30903.2 31276.0 31813.2 31815.0 32416.7 4329.5 4372.2 4433.1 4531.4 4644.3 302431.8 303583.0 303952.0 304064.0 304884.0 56091.0 56322.4 56504.2 56509.0 56531.0 31874.2 32369.0 32410.7 32422.0 32446.7 15247.2 15283.4 15377.1 15390.9 15403.2 10676.4 10958.1 10989.1 11438.1 11637.7 17745.9 18621.3 18659.5 18677.7 18699.3 6798.4 6817.3 7018.5 7105.4 7166.5 896.5 994.4 1013.8 1101.3 1107.5 30870.0 31380.4 31733.8 31800.8 32381.7 1019.6 1073.6 1077.1 1091.0 1150.9 15461.9 16308.8 16454.3 16558.6 16559.5 533776.0 551587.0 552167.0 553039.0 553325.0 56736.7 57169.0 57405.1 57442.4 57529.7 1213.6 1293.3 1309.2 1344.3 1390.6 30617.2 32483.5 32858.3 33310.7 33990.5 47643.3 48425.8 48489.3 48498.8 48503.0 385010.0 387279.0 387342.0 388057.0 389968.0 40829.8 41393.8 41448.8 41522.2 44961.8 45829.1 45924.5 46463.8 46597.3 47882.5 38838.0 39206.2 39215.2 39230.8 40694.8 38223.2 38696.8 38868.6 39183.0 39363.8 38945.2 39760.0 39796.6 40145.4 40176.4 30452.4 33835.2 34187.0 35017.5 35056.3 28036.6 28129.0 28151.4 28202.6 28316.4 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | [#2] jquery.html Cycles:180 Average:27495.67 Median:4112.06 stddev:77273.34 (1879.2%) stddev-sans-first:77463.25 07:53:20 INFO - PROCESS | 23456 | Values: 356.4 372.5 375.8 383.0 408.4 14128.4 17942.9 18238.8 18327.9 19541.1 16631.8 17655.2 18182.3 18334.4 18600.1 5109.9 5335.5 5340.0 5342.8 5343.1 3817.1 4126.5 5238.3 5317.5 5318.0 4087.8 6470.3 6474.5 6478.2 6480.6 5828.0 5875.4 6140.9 6455.8 6460.9 5655.3 5657.3 5658.3 5659.2 5661.9 13587.7 15589.4 21335.8 22149.6 22159.9 2013.5 2117.5 2164.2 2264.9 2780.4 9313.3 9587.3 10018.5 10108.6 10645.1 1849.7 1893.1 1927.6 1965.4 1985.2 74566.6 80625.0 81522.6 82185.2 84442.6 9296.5 9428.7 9827.8 9906.9 9910.5 13060.0 13108.4 13131.0 13132.0 13142.0 7115.3 7369.5 7422.6 7561.7 7571.5 7266.3 7316.5 7350.2 7466.0 7543.0 3616.2 3808.0 4071.5 4097.6 4147.3 1932.7 2026.7 2027.3 2041.8 2059.3 856.7 926.8 929.2 947.4 975.4 18426.3 19352.8 19534.0 19865.7 19926.7 883.7 933.3 940.0 942.8 953.0 10331.2 10577.0 10760.2 10800.3 10828.0 453112.0 466526.0 470939.0 473011.0 482273.0 51942.2 75769.6 86417.6 88084.0 88992.6 953.6 980.8 991.6 1003.3 1013.5 15353.5 16852.5 17355.3 17418.9 17470.3 40616.4 41439.4 42018.8 42079.6 42334.0 51899.0 53123.2 53917.2 55051.2 55473.0 8327.5 8339.5 8396.5 8580.1 8665.3 12479.1 12889.0 13048.0 13185.3 13309.7 11120.8 11385.3 11393.7 11613.5 11768.6 10950.8 11137.8 11266.4 11431.8 11831.5 11657.3 11720.6 11911.8 12066.6 12125.4 8415.5 8490.3 8587.8 8627.3 8681.3 9866.3 11472.3 11529.7 11616.9 11863.8 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | [#3] mootools.html Cycles:180 Average:5518.13 Median:4852.05 stddev:3217.81 (66.3%) stddev-sans-first:3211.27 07:53:20 INFO - PROCESS | 23456 | Values: 1307.2 1430.3 1442.4 1568.3 1727.5 7435.5 8238.8 8444.0 8514.2 8838.8 8586.0 8651.1 8661.4 8665.7 8666.7 2235.6 2252.0 2260.8 2263.9 2266.5 2174.6 2228.5 2228.6 2234.7 2235.1 2173.3 2177.7 2180.5 2185.1 2221.7 2150.2 2168.5 2173.5 2173.9 2174.7 1995.0 1995.7 1996.3 1997.6 2000.8 8123.0 8240.5 8242.6 8251.0 8445.2 4792.4 4991.3 4995.1 5002.5 5012.0 3278.8 3297.8 3333.8 3367.0 3377.4 1030.6 1045.4 1048.7 1049.6 1100.8 11832.4 11946.5 12012.3 12024.3 12048.5 4016.0 4199.2 4310.1 4392.6 4409.1 9451.9 9457.3 9463.5 9466.7 9477.3 4844.9 4845.3 4904.5 4905.9 4911.1 3827.6 3909.9 4245.1 4541.2 4545.0 3451.6 3514.9 3563.3 3564.8 3587.1 1019.6 1024.5 1026.2 1030.4 1032.3 857.7 944.8 948.2 966.6 966.9 5644.5 5648.2 5848.2 5849.4 5905.8 428.4 451.2 451.9 452.3 454.0 3669.8 3755.1 3790.3 3803.9 3926.4 10546.1 10809.7 10892.4 10901.1 10903.9 9505.9 9915.5 10097.5 10755.8 10794.9 8043.2 8049.0 8200.2 8211.7 8214.6 5849.3 6003.6 6025.8 6034.9 6050.8 9681.8 9748.8 9753.2 9863.7 10005.0 8601.9 9853.6 9975.4 10018.4 10066.3 6637.4 7420.6 7543.7 7608.4 7815.2 8123.4 8154.1 8207.8 8282.8 8328.0 6901.8 7008.5 7384.9 7398.5 7399.2 7165.4 7165.9 7222.6 7299.1 7362.4 6781.2 6800.4 6831.9 6854.4 6876.9 4810.6 4842.9 4858.8 4935.2 5114.5 4864.3 4917.5 4943.5 5011.2 5101.7 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | [#4] prototype.html Cycles:180 Average:4540.80 Median:5024.71 stddev:1317.94 (26.2%) stddev-sans-first:1282.98 07:53:20 INFO - PROCESS | 23456 | Values: 318.9 324.4 326.9 336.9 336.9 5308.0 5331.7 5346.1 5346.9 5350.4 4994.7 5051.1 5134.6 5184.4 5302.9 4134.7 4136.5 4138.3 4163.6 4181.8 4166.5 4166.9 4191.4 4217.0 4217.7 3779.0 3923.0 4026.6 4136.0 4157.9 4124.5 4126.1 4133.3 4134.0 4135.1 3681.8 3687.6 3690.8 3691.9 3695.8 5090.8 5112.2 5114.8 5220.1 5240.0 4743.7 4749.5 4757.1 4800.0 4801.3 5522.6 5528.7 5538.4 5556.4 5602.5 3914.6 4060.6 4090.7 4096.8 4109.7 5664.7 5667.4 5672.8 5682.7 5705.9 4133.4 4134.6 4137.2 4175.8 4177.4 3869.2 4103.9 4233.8 4235.8 4242.2 4738.2 4743.7 4750.4 4756.4 4782.9 5702.4 5708.1 5708.7 5715.4 5716.9 1358.3 1372.9 1418.5 1428.6 1431.4 909.4 910.8 926.6 931.9 935.8 4696.0 4736.4 4744.2 4776.5 4808.0 4961.5 4967.8 5018.4 5080.2 5085.9 4534.6 4726.0 4733.8 4734.8 4775.4 2650.8 2652.2 2659.6 2666.3 2680.5 5103.9 5112.5 5168.0 5173.2 5191.9 4813.4 4969.4 5019.9 5063.8 5141.5 5062.6 5095.3 5108.3 5132.1 5211.5 4983.3 5023.1 5026.3 5073.5 5076.9 4923.0 4959.3 5039.9 5284.5 5740.2 5827.6 5866.1 5918.7 5919.5 5940.7 4034.8 4114.0 4137.1 4149.3 4150.5 5422.7 5568.9 5713.8 5754.8 5802.2 5509.9 5587.2 5587.7 5593.3 5601.9 5365.2 5594.4 5620.7 5649.5 5693.5 5571.5 5576.5 5585.7 5592.9 5599.0 5626.5 5628.3 5689.3 5730.6 5735.3 5264.6 5513.8 5531.4 5535.8 5585.4 07:53:20 INFO - PROCESS | 23456 | 07:53:20 INFO - PROCESS | 23456 | [#5] yui.html Cycles:180 Average:14956.64 Median:530.22 stddev:18624.00 (3512.5%) stddev-sans-first:18645.14 07:53:20 INFO - PROCESS | 23456 | Values: 622.3 646.2 712.8 741.6 821.7 12253.8 13204.2 13236.5 13260.5 13270.3 12271.6 12353.4 12372.0 12377.1 12391.9 653.2 663.9 784.4 807.1 810.7 775.9 796.8 800.8 805.6 806.0 845.8 847.8 848.6 848.6 849.6 691.1 846.3 846.4 846.5 847.5 515.8 600.2 611.6 614.0 616.9 12101.9 12134.8 12150.3 12181.5 12190.6 11974.9 12070.9 12086.9 12462.6 12626.0 11912.1 12050.1 12119.6 12150.9 12170.6 4297.8 4306.2 4329.5 4339.6 4339.9 60677.0 60920.7 61510.4 63543.0 64088.0 9681.7 9730.9 9749.3 9943.3 10126.2 17049.9 17199.9 17276.7 17282.7 17285.1 7352.1 7379.3 7692.1 7741.0 7809.3 4311.8 4410.9 4468.2 4484.3 4573.7 3077.2 3172.2 3174.6 3174.6 3175.5 538.1 542.7 559.7 561.6 566.5 538.6 584.0 599.3 646.4 651.1 7819.2 7921.6 8086.3 8126.0 8133.3 518.3 522.4 556.3 596.3 597.1 3714.7 3827.3 3837.7 3872.1 3988.8 50701.0 50809.2 50878.7 50958.2 73896.0 44970.8 45251.8 46268.8 61433.7 61960.7 56241.4 56557.4 59106.1 59107.0 61307.7 7780.5 8327.5 8723.2 8735.2 8964.7 41141.4 41536.4 41669.4 42334.6 44961.3 53974.5 54868.0 55485.0 57067.4 61132.7 9990.7 10221.1 10396.9 10505.4 11411.0 12221.1 12294.6 12516.4 12661.3 13020.8 10201.7 11490.2 11558.8 11838.7 12265.2 11062.5 11532.8 11624.6 11632.7 11859.9 11243.8 11384.1 11387.1 11419.5 11777.8 9434.3 9573.8 9659.8 9884.9 10214.5 8987.6 9197.3 9206.3 9363.9 9522.4 07:53:20 INFO - PROCESS | 23456 | -------- Summary: end -------- 07:53:20 INFO - PROCESS | 23456 | 07:53:21 INFO - TEST-INFO | 23456: exit 0 07:53:21 INFO - TEST-OK | dromaeo_css | took 1118346ms 07:53:21 INFO - TEST-START | kraken 07:53:21 INFO - operating with platform_type : linux_ 07:53:21 INFO - Initialising browser for kraken test... 07:53:21 INFO - TEST-INFO | started process 23795 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdFBFhF/profile http://localhost:56968/getInfo.html) 07:53:25 INFO - PROCESS | 23795 | __metrics Screen width/height:1600/1200 07:53:25 INFO - PROCESS | 23795 | colorDepth:24 07:53:25 INFO - PROCESS | 23795 | Browser inner width/height: 1024/697 07:53:25 INFO - PROCESS | 23795 | __metrics 07:53:26 INFO - PROCESS | 23795 | 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" 07:53:26 INFO - PROCESS | 23795 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:53:26 INFO - PROCESS | 23795 | console.error: 07:53:26 INFO - PROCESS | 23795 | Message: Error: Connection closed before committing the transaction. 07:53:26 INFO - PROCESS | 23795 | Stack: 07:53:26 INFO - PROCESS | 23795 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 07:53:26 INFO - PROCESS | 23795 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:53:26 INFO - PROCESS | 23795 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:53:26 INFO - PROCESS | 23795 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:53:26 INFO - PROCESS | 23795 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:53:26 INFO - PROCESS | 23795 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 07:53:26 INFO - PROCESS | 23795 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 07:53:26 INFO - PROCESS | 23795 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 07:53:26 INFO - PROCESS | 23795 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 07:53:26 INFO - PROCESS | 23795 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 07:53:26 INFO - PROCESS | 23795 | Barrier/this.client.addBlocker/promise13.1% 07:54:24 INFO - TinderboxPrint: I/O read bytes / time
2,220,032 / 1,304 07:54:24 INFO - TinderboxPrint: I/O write bytes / time
391,925,760 / 329,076 07:54:24 INFO - TinderboxPrint: CPU idle
8,308.2 (86.9%) 07:54:24 INFO - TinderboxPrint: CPU user
1,198.4 (12.5%) 07:54:24 INFO - TinderboxPrint: Swap in / out
0 / 0 07:54:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103383040; Read time: 0; Write time: 90324 07:54:24 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 2215936; Write bytes: 288051200; Read time: 1296; Write time: 238700 07:54:24 INFO - Running post-run listener: _upload_blobber_files 07:54:24 INFO - Blob upload gear active. 07:54:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:54:24 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 07:54:24 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'] 07:54:24 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 07:54:25 INFO - (blobuploader) - INFO - Open directory for files ... 07:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:26 INFO - (blobuploader) - INFO - Done attempting. 07:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:54:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:30 INFO - (blobuploader) - INFO - Done attempting. 07:54:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:54:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:54:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:30 INFO - (blobuploader) - INFO - Done attempting. 07:54:30 INFO - (blobuploader) - INFO - Iteration through files over. 07:54:30 INFO - Return code: 0 07:54:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:54:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:54:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37a18bbb0a441bced1f9cf7a980e37a69eb9188528128203713806950061742ae9ab0cee1fb4a827d887078eab1b6da80edb12c8673a447c60ada7443cc8804d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ae029200348d56ee68272b11da54d7e2c565be15d7ba41f5ddeeffa0e925542ade67ba2b3fb2e8290fd1f23df7e422ede43e7a7710d18d00038f8e0bada712b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ac9965f1e2ca723fc049032c9855a463f83160d5b09823b37470bf95d64aa5a814c6a9cb1cb9caf839fdc96d07d5ea00cbcd018d873744852bc86014508d6f1"} 07:54:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:54:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:54:30 INFO - Contents: 07:54:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/37a18bbb0a441bced1f9cf7a980e37a69eb9188528128203713806950061742ae9ab0cee1fb4a827d887078eab1b6da80edb12c8673a447c60ada7443cc8804d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ae029200348d56ee68272b11da54d7e2c565be15d7ba41f5ddeeffa0e925542ade67ba2b3fb2e8290fd1f23df7e422ede43e7a7710d18d00038f8e0bada712b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ac9965f1e2ca723fc049032c9855a463f83160d5b09823b37470bf95d64aa5a814c6a9cb1cb9caf839fdc96d07d5ea00cbcd018d873744852bc86014508d6f1"} 07:54:30 INFO - Running post-run listener: copy_logs_to_upload_dir 07:54:30 INFO - Copying logs to upload dir... 07:54:30 INFO - mkdir: /builds/slave/test/build/upload/logs 07:54:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.788611 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-10-25 07:54:29.574692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 07:54:29.579697) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195 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/37a18bbb0a441bced1f9cf7a980e37a69eb9188528128203713806950061742ae9ab0cee1fb4a827d887078eab1b6da80edb12c8673a447c60ada7443cc8804d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ae029200348d56ee68272b11da54d7e2c565be15d7ba41f5ddeeffa0e925542ade67ba2b3fb2e8290fd1f23df7e422ede43e7a7710d18d00038f8e0bada712b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ac9965f1e2ca723fc049032c9855a463f83160d5b09823b37470bf95d64aa5a814c6a9cb1cb9caf839fdc96d07d5ea00cbcd018d873744852bc86014508d6f1"} build_url:https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011383 build_url: 'https://queue.taskcluster.net/v1/task/D9kc5D7zTzSgw0ROjcrCzw/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/37a18bbb0a441bced1f9cf7a980e37a69eb9188528128203713806950061742ae9ab0cee1fb4a827d887078eab1b6da80edb12c8673a447c60ada7443cc8804d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ae029200348d56ee68272b11da54d7e2c565be15d7ba41f5ddeeffa0e925542ade67ba2b3fb2e8290fd1f23df7e422ede43e7a7710d18d00038f8e0bada712b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ac9965f1e2ca723fc049032c9855a463f83160d5b09823b37470bf95d64aa5a814c6a9cb1cb9caf839fdc96d07d5ea00cbcd018d873744852bc86014508d6f1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 07:54:29.605142) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:54:29.605502) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1477404455.34194-2108086195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004394 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 07:54:29.687223) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 07:54:29.687878) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 07:54:29.688166) ========= ========= Total master_lag: 0.17 =========