builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1477784401.11 results: success (0) buildid: 20161029151843 builduid: 4f307a5eef534c198acf5cc6033d956b revision: 40cd4eb145ffd5d0c4d698965ed3870903c70c2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.109894) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.110441) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.110737) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.126262) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.126672) ========= 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=41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-29 16:40:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.6M=0s 2016-10-29 16:40:01 (37.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103127 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.243863) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.244175) ========= 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=41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020271 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:01.294169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 16:40:01.294537) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 40cd4eb145ffd5d0c4d698965ed3870903c70c2f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 40cd4eb145ffd5d0c4d698965ed3870903c70c2f --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=41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-29 16:40:01,424 truncating revision to first 12 chars 2016-10-29 16:40:01,424 Setting DEBUG logging. 2016-10-29 16:40:01,424 attempt 1/10 2016-10-29 16:40:01,424 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/40cd4eb145ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 16:40:02,718 unpacking tar archive at: mozilla-inbound-40cd4eb145ff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.596115 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 16:40:02.904539) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:02.905009) ========= script_repo_revision: 40cd4eb145ffd5d0c4d698965ed3870903c70c2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:02.905598) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:02.905956) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 16:40:02.918258) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-10-29 16:40:02.918714) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:40:03 INFO - MultiFileLogger online at 20161029 16:40:03 in /builds/slave/test 16:40:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:40:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:40:03 INFO - {'append_to_log': False, 16:40:03 INFO - 'base_work_dir': '/builds/slave/test', 16:40:03 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:40:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:40:03 INFO - 'branch': 'Mozilla-Inbound', 16:40:03 INFO - 'buildbot_json_path': 'buildprops.json', 16:40:03 INFO - 'config_files': ('talos/linux_config.py',), 16:40:03 INFO - 'default_actions': ('clobber', 16:40:03 INFO - 'read-buildbot-config', 16:40:03 INFO - 'download-and-extract', 16:40:03 INFO - 'populate-webroot', 16:40:03 INFO - 'create-virtualenv', 16:40:03 INFO - 'install', 16:40:03 INFO - 'run-tests'), 16:40:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:40:03 INFO - 'download_minidump_stackwalk': True, 16:40:03 INFO - 'download_symbols': 'ondemand', 16:40:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:40:03 INFO - 'tooltool.py': '/tools/tooltool.py', 16:40:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:40:03 INFO - '/tools/misc-python/virtualenv.py')}, 16:40:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:40:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:40:03 INFO - 'installer_path': 'installer.exe', 16:40:03 INFO - 'log_level': 'info', 16:40:03 INFO - 'log_name': 'talos', 16:40:03 INFO - 'log_to_console': True, 16:40:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:40:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:40:03 INFO - 'opt_config_files': (), 16:40:03 INFO - 'pip_index': False, 16:40:03 INFO - 'sps_profile': False, 16:40:03 INFO - 'sps_profile_interval': 0, 16:40:03 INFO - 'suite': 'dromaeojs', 16:40:03 INFO - 'system_bits': '32', 16:40:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:40:03 INFO - 'title': 'talos-linux64-ix-024', 16:40:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:40:03 INFO - 'use_talos_json': True, 16:40:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:40:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:40:03 INFO - 'work_dir': 'build'} 16:40:03 INFO - [mozharness: 2016-10-29 23:40:03.145618Z] Running clobber step. 16:40:03 INFO - Running pre-action listener: _resource_record_pre_action 16:40:03 INFO - Running main action method: clobber 16:40:03 INFO - rmtree: /builds/slave/test/build 16:40:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:40:03 INFO - Running post-action listener: _resource_record_post_action 16:40:03 INFO - [mozharness: 2016-10-29 23:40:03.604275Z] Finished clobber step (success) 16:40:03 INFO - [mozharness: 2016-10-29 23:40:03.604375Z] Running read-buildbot-config step. 16:40:03 INFO - Running pre-action listener: _resource_record_pre_action 16:40:03 INFO - Running main action method: read_buildbot_config 16:40:03 INFO - Using buildbot properties: 16:40:03 INFO - { 16:40:03 INFO - "project": "", 16:40:03 INFO - "product": "firefox", 16:40:03 INFO - "script_repo_revision": "production", 16:40:03 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:40:03 INFO - "repository": "", 16:40:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:40:03 INFO - "buildid": "20161029151843", 16:40:03 INFO - "stage_platform": "linux64-pgo", 16:40:03 INFO - "basedir": "/builds/slave/test", 16:40:03 INFO - "buildnumber": 2681, 16:40:03 INFO - "platform": "ubuntu64_hw", 16:40:03 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:40:03 INFO - "slavebuilddir": "test", 16:40:03 INFO - "branch": "mozilla-inbound", 16:40:03 INFO - "revision": "40cd4eb145ffd5d0c4d698965ed3870903c70c2f", 16:40:03 INFO - "slavename": "talos-linux64-ix-024", 16:40:03 INFO - "builduid": "4f307a5eef534c198acf5cc6033d956b", 16:40:03 INFO - "repo_path": "integration/mozilla-inbound" 16:40:03 INFO - } 16:40:03 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 16:40:03 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 16:40:03 INFO - Running post-action listener: _resource_record_post_action 16:40:03 INFO - [mozharness: 2016-10-29 23:40:03.606742Z] Finished read-buildbot-config step (success) 16:40:03 INFO - [mozharness: 2016-10-29 23:40:03.606831Z] Running download-and-extract step. 16:40:03 INFO - Running pre-action listener: _resource_record_pre_action 16:40:03 INFO - Running main action method: download_and_extract 16:40:03 INFO - mkdir: /builds/slave/test/build/tests 16:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:03 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 16:40:03 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/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 16:40:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/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 16:40:03 INFO - Downloaded 1361 bytes. 16:40:03 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 16:40:03 INFO - Using the following test package requirements: 16:40:03 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 16:40:03 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:40:03 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:40:03 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'jsshell-linux-x86_64.zip'], 16:40:03 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:40:03 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 16:40:03 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:40:03 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:40:03 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:40:03 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 16:40:03 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:40:03 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:40:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 16:40:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:40:03 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:40:03 INFO - Http code: 200 16:40:03 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 16:40:03 INFO - x-amz-cf-id: iczi-dciWx62n2-nIUKZZDKoCUEol0RJLnjBhKT8KgxqLh_Xg9aG1w== 16:40:03 INFO - x-amz-replication-status: COMPLETED 16:40:03 INFO - x-amz-version-id: q5420wunDo3v0Htz4dQM5haxY21wPSbw 16:40:03 INFO - Expected file size: 22006156 16:40:03 INFO - Obtained file size: 22006156 16:40:08 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 16:40:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 16:40:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:40:08 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:40:08 INFO - Http code: 200 16:40:08 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 16:40:08 INFO - x-amz-cf-id: L55W2Ct9EBaDE_x6bGASiX3hHjnFIUx5m9J-jMMQKgjx2lIyI7KFUg== 16:40:08 INFO - x-amz-replication-status: COMPLETED 16:40:08 INFO - x-amz-version-id: q5420wunDo3v0Htz4dQM5haxY21wPSbw 16:40:08 INFO - Expected file size: 22006156 16:40:08 INFO - Obtained file size: 22006156 16:40:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 16:40:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:40:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 16:40:12 INFO - Http code: 200 16:40:12 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 16:40:12 INFO - x-amz-cf-id: 217ThMku1lh4B8C_fhabtCTYNWGVngt8ggkFD93sA6Hgm_TeJI8RdA== 16:40:12 INFO - x-amz-replication-status: COMPLETED 16:40:12 INFO - x-amz-version-id: QrdhzoM2CLkn_IP4LHM4SUDa4GiJrMdf 16:40:13 INFO - Expected file size: 14409038 16:40:13 INFO - Obtained file size: 14409038 16:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:13 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 16:40:13 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/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 16:40:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/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 16:40:14 INFO - Downloaded 59630170 bytes. 16:40:14 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 16:40:14 INFO - mkdir: /builds/slave/test/properties 16:40:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:40:14 INFO - Writing to file /builds/slave/test/properties/build_url 16:40:14 INFO - Contents: 16:40:14 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 16:40:14 INFO - Running post-action listener: _resource_record_post_action 16:40:14 INFO - Running post-action listener: set_extra_try_arguments 16:40:14 INFO - [mozharness: 2016-10-29 23:40:14.445288Z] Finished download-and-extract step (success) 16:40:14 INFO - [mozharness: 2016-10-29 23:40:14.445553Z] Running populate-webroot step. 16:40:14 INFO - Running pre-action listener: _resource_record_pre_action 16:40:14 INFO - Running main action method: populate_webroot 16:40:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:40:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:40:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:40:14 INFO - '--tppagecycles', 16:40:14 INFO - '7'], 16:40:14 INFO - 'tests': ['tsvgm']}}, 16:40:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:40:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:40:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:40:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:40:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['damp', 'tps']}, 16:40:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'tests': ['damp', 'tps']}, 16:40:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['dromaeo_dom']}, 16:40:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:40:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['basic_compositor_video']}, 16:40:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:40:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['a11yr', 16:40:14 INFO - 'ts_paint', 16:40:14 INFO - 'tpaint', 16:40:14 INFO - 'sessionrestore', 16:40:14 INFO - 'sessionrestore_no_auto_restore', 16:40:14 INFO - 'tabpaint']}, 16:40:14 INFO - 'other-e10s': {'tests': ['a11yr', 16:40:14 INFO - 'ts_paint', 16:40:14 INFO - 'tpaint', 16:40:14 INFO - 'sessionrestore', 16:40:14 INFO - 'sessionrestore_no_auto_restore', 16:40:14 INFO - 'tabpaint']}, 16:40:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:40:14 INFO - 'ts_paint', 16:40:14 INFO - 'tpaint', 16:40:14 INFO - 'sessionrestore', 16:40:14 INFO - 'sessionrestore_no_auto_restore', 16:40:14 INFO - 'tabpaint']}, 16:40:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:40:14 INFO - 'ts_paint', 16:40:14 INFO - 'tpaint', 16:40:14 INFO - 'sessionrestore', 16:40:14 INFO - 'sessionrestore_no_auto_restore', 16:40:14 INFO - 'tabpaint']}, 16:40:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['a11yr', 16:40:14 INFO - 'ts_paint', 16:40:14 INFO - 'tpaint', 16:40:14 INFO - 'sessionrestore', 16:40:14 INFO - 'sessionrestore_no_auto_restore', 16:40:14 INFO - 'tabpaint']}, 16:40:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['a11yr', 16:40:14 INFO - 'ts_paint', 16:40:14 INFO - 'tpaint', 16:40:14 INFO - 'sessionrestore', 16:40:14 INFO - 'sessionrestore_no_auto_restore', 16:40:14 INFO - 'tabpaint']}, 16:40:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['tsvgx', 16:40:14 INFO - 'tsvgr_opacity', 16:40:14 INFO - 'tart', 16:40:14 INFO - 'tscrollx', 16:40:14 INFO - 'cart']}, 16:40:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:40:14 INFO - 'tsvgr_opacity', 16:40:14 INFO - 'tart', 16:40:14 INFO - 'tscrollx', 16:40:14 INFO - 'cart']}, 16:40:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'talos_options': ['--disable-e10s'], 16:40:14 INFO - 'tests': ['tp5o']}, 16:40:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'tests': ['tp5o']}, 16:40:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'talos_options': ['--disable-e10s', 16:40:14 INFO - '--xperf_path', 16:40:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:14 INFO - 'tests': ['tp5n']}, 16:40:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:40:14 INFO - 'talos_options': ['--xperf_path', 16:40:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:14 INFO - 'tests': ['tp5n']}}, 16:40:14 INFO - 'talos.zip': {'path': '', 16:40:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:40:14 INFO - Running post-action listener: _resource_record_post_action 16:40:14 INFO - [mozharness: 2016-10-29 23:40:14.466360Z] Finished populate-webroot step (success) 16:40:14 INFO - [mozharness: 2016-10-29 23:40:14.466447Z] Running create-virtualenv step. 16:40:14 INFO - Running pre-action listener: _resource_record_pre_action 16:40:14 INFO - Running main action method: create_virtualenv 16:40:14 INFO - Creating virtualenv /builds/slave/test/build/venv 16:40:14 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 16:40:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:40:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:40:14 INFO - Using real prefix '/usr' 16:40:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:40:15 INFO - Installing distribute.............................................................................................................................................................................................done. 16:40:17 INFO - Installing pip.................done. 16:40:17 INFO - Return code: 0 16:40:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:40:17 INFO - Reading from file tmpfile_stdout 16:40:17 INFO - Output received: 16:40:17 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) 16:40:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:40:17 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')]} 16:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:17 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')]} 16:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:17 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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:17 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 16:40:17 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 16:40:17 INFO - Using env: {'DISPLAY': ':0', 16:40:17 INFO - 'HOME': '/home/cltbld', 16:40:17 INFO - 'LANG': 'en_US.UTF-8', 16:40:17 INFO - 'LANGUAGE': 'en_US:en', 16:40:17 INFO - 'LOGNAME': 'cltbld', 16:40:17 INFO - 'MAIL': '/var/mail/cltbld', 16:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:17 INFO - 'MOZ_NO_REMOTE': '1', 16:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:17 INFO - 'NO_EM_RESTART': '1', 16:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:17 INFO - 'PWD': '/builds/slave/test', 16:40:17 INFO - 'SHELL': '/bin/bash', 16:40:17 INFO - 'SHLVL': '1', 16:40:17 INFO - 'TERM': 'linux', 16:40:17 INFO - 'TMOUT': '86400', 16:40:17 INFO - 'USER': 'cltbld', 16:40:17 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:17 INFO - '_': '/tools/buildbot/bin/python'} 16:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:17 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 16:40:17 INFO - Cleaning up... 16:40:17 INFO - Return code: 0 16:40:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:40:17 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')]} 16:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:17 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')]} 16:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:17 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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:17 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 16:40:17 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 16:40:17 INFO - Using env: {'DISPLAY': ':0', 16:40:17 INFO - 'HOME': '/home/cltbld', 16:40:17 INFO - 'LANG': 'en_US.UTF-8', 16:40:17 INFO - 'LANGUAGE': 'en_US:en', 16:40:17 INFO - 'LOGNAME': 'cltbld', 16:40:17 INFO - 'MAIL': '/var/mail/cltbld', 16:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:17 INFO - 'MOZ_NO_REMOTE': '1', 16:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:17 INFO - 'NO_EM_RESTART': '1', 16:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:17 INFO - 'PWD': '/builds/slave/test', 16:40:17 INFO - 'SHELL': '/bin/bash', 16:40:17 INFO - 'SHLVL': '1', 16:40:17 INFO - 'TERM': 'linux', 16:40:17 INFO - 'TMOUT': '86400', 16:40:17 INFO - 'USER': 'cltbld', 16:40:17 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:17 INFO - '_': '/tools/buildbot/bin/python'} 16:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:17 INFO - Downloading/unpacking psutil>=3.1.1 16:40: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 16:40: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 16:40: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 16:40: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 16:40: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 16:40: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 16:40:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:40:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:19 INFO - Installing collected packages: psutil 16:40:19 INFO - Running setup.py install for psutil 16:40:19 INFO - building 'psutil._psutil_linux' extension 16:40:19 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 16:40:19 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 16:40:20 INFO - building 'psutil._psutil_posix' extension 16:40:20 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 16:40:20 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 16:40:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:20 INFO - Successfully installed psutil 16:40:20 INFO - Cleaning up... 16:40:20 INFO - Return code: 0 16:40:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:20 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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:20 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 16:40:20 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 16:40:20 INFO - Using env: {'DISPLAY': ':0', 16:40:20 INFO - 'HOME': '/home/cltbld', 16:40:20 INFO - 'LANG': 'en_US.UTF-8', 16:40:20 INFO - 'LANGUAGE': 'en_US:en', 16:40:20 INFO - 'LOGNAME': 'cltbld', 16:40:20 INFO - 'MAIL': '/var/mail/cltbld', 16:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:20 INFO - 'MOZ_NO_REMOTE': '1', 16:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:20 INFO - 'NO_EM_RESTART': '1', 16:40:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:20 INFO - 'PWD': '/builds/slave/test', 16:40:20 INFO - 'SHELL': '/bin/bash', 16:40:20 INFO - 'SHLVL': '1', 16:40:20 INFO - 'TERM': 'linux', 16:40:20 INFO - 'TMOUT': '86400', 16:40:20 INFO - 'USER': 'cltbld', 16:40:20 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:20 INFO - '_': '/tools/buildbot/bin/python'} 16:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:40:20 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 16:40:20 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 16:40:20 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 16:40:20 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 16:40:20 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 16:40:20 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 16:40:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:40:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:40:22 INFO - Installing collected packages: mozsystemmonitor 16:40:22 INFO - Running setup.py install for mozsystemmonitor 16:40:22 INFO - Successfully installed mozsystemmonitor 16:40:22 INFO - Cleaning up... 16:40:22 INFO - Return code: 0 16:40:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:40:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:40:22 INFO - Using env: {'DISPLAY': ':0', 16:40:22 INFO - 'HOME': '/home/cltbld', 16:40:22 INFO - 'LANG': 'en_US.UTF-8', 16:40:22 INFO - 'LANGUAGE': 'en_US:en', 16:40:22 INFO - 'LOGNAME': 'cltbld', 16:40:22 INFO - 'MAIL': '/var/mail/cltbld', 16:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:22 INFO - 'MOZ_NO_REMOTE': '1', 16:40:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:22 INFO - 'NO_EM_RESTART': '1', 16:40:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:22 INFO - 'PWD': '/builds/slave/test', 16:40:22 INFO - 'SHELL': '/bin/bash', 16:40:22 INFO - 'SHLVL': '1', 16:40:22 INFO - 'TERM': 'linux', 16:40:22 INFO - 'TMOUT': '86400', 16:40:22 INFO - 'USER': 'cltbld', 16:40:22 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:22 INFO - '_': '/tools/buildbot/bin/python'} 16:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:22 INFO - Downloading/unpacking jsonschema==2.5.1 16:40: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 16:40: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 16:40: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 16:40: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 16:40: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 16:40: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 16:40:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:40:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:24 INFO - Downloading functools32-3.2.3-2.tar.gz 16:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:40:25 INFO - warning: no files found matching '*.txt' 16:40:25 INFO - no previously-included directories found matching 'build' 16:40:25 INFO - no previously-included directories found matching 'dist' 16:40:25 INFO - no previously-included directories found matching '.git*' 16:40:25 INFO - Installing collected packages: jsonschema, functools32 16:40:25 INFO - Running setup.py install for functools32 16:40:25 INFO - warning: no files found matching '*.txt' 16:40:25 INFO - no previously-included directories found matching 'build' 16:40:25 INFO - no previously-included directories found matching 'dist' 16:40:25 INFO - no previously-included directories found matching '.git*' 16:40:25 INFO - Successfully installed jsonschema functools32 16:40:25 INFO - Cleaning up... 16:40:25 INFO - Return code: 0 16:40:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:40: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')]} 16:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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')]} 16:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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', '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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:40: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 functools32==3.2.3-2 16:40:25 INFO - Using env: {'DISPLAY': ':0', 16:40:25 INFO - 'HOME': '/home/cltbld', 16:40:25 INFO - 'LANG': 'en_US.UTF-8', 16:40:25 INFO - 'LANGUAGE': 'en_US:en', 16:40:25 INFO - 'LOGNAME': 'cltbld', 16:40:25 INFO - 'MAIL': '/var/mail/cltbld', 16:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:25 INFO - 'MOZ_NO_REMOTE': '1', 16:40:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:25 INFO - 'NO_EM_RESTART': '1', 16:40:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:25 INFO - 'PWD': '/builds/slave/test', 16:40:25 INFO - 'SHELL': '/bin/bash', 16:40:25 INFO - 'SHLVL': '1', 16:40:25 INFO - 'TERM': 'linux', 16:40:25 INFO - 'TMOUT': '86400', 16:40:25 INFO - 'USER': 'cltbld', 16:40:25 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:25 INFO - '_': '/tools/buildbot/bin/python'} 16:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:40:25 INFO - Cleaning up... 16:40:25 INFO - Return code: 0 16:40:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:40: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')]} 16:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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')]} 16:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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', '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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:40: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 blobuploader==1.2.4 16:40:25 INFO - Using env: {'DISPLAY': ':0', 16:40:25 INFO - 'HOME': '/home/cltbld', 16:40:25 INFO - 'LANG': 'en_US.UTF-8', 16:40:25 INFO - 'LANGUAGE': 'en_US:en', 16:40:25 INFO - 'LOGNAME': 'cltbld', 16:40:25 INFO - 'MAIL': '/var/mail/cltbld', 16:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:25 INFO - 'MOZ_NO_REMOTE': '1', 16:40:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:25 INFO - 'NO_EM_RESTART': '1', 16:40:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:25 INFO - 'PWD': '/builds/slave/test', 16:40:25 INFO - 'SHELL': '/bin/bash', 16:40:25 INFO - 'SHLVL': '1', 16:40:25 INFO - 'TERM': 'linux', 16:40:25 INFO - 'TMOUT': '86400', 16:40:25 INFO - 'USER': 'cltbld', 16:40:25 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:25 INFO - '_': '/tools/buildbot/bin/python'} 16:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:25 INFO - Downloading/unpacking blobuploader==1.2.4 16:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:27 INFO - Downloading blobuploader-1.2.4.tar.gz 16:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:40:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:40:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:40:28 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 16:40:28 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 16:40:28 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 16:40:28 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 16:40:28 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 16:40:28 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 16:40:28 INFO - Downloading docopt-0.6.1.tar.gz 16:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:40:28 INFO - Installing collected packages: blobuploader, requests, docopt 16:40:28 INFO - Running setup.py install for blobuploader 16:40:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:40:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:40:28 INFO - Running setup.py install for requests 16:40:28 INFO - Running setup.py install for docopt 16:40:28 INFO - Successfully installed blobuploader requests docopt 16:40:28 INFO - Cleaning up... 16:40:29 INFO - Return code: 0 16:40:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:29 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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:29 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 16:40:29 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 16:40:29 INFO - Using env: {'DISPLAY': ':0', 16:40:29 INFO - 'HOME': '/home/cltbld', 16:40:29 INFO - 'LANG': 'en_US.UTF-8', 16:40:29 INFO - 'LANGUAGE': 'en_US:en', 16:40:29 INFO - 'LOGNAME': 'cltbld', 16:40:29 INFO - 'MAIL': '/var/mail/cltbld', 16:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:29 INFO - 'MOZ_NO_REMOTE': '1', 16:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:29 INFO - 'NO_EM_RESTART': '1', 16:40:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:29 INFO - 'PWD': '/builds/slave/test', 16:40:29 INFO - 'SHELL': '/bin/bash', 16:40:29 INFO - 'SHLVL': '1', 16:40:29 INFO - 'TERM': 'linux', 16:40:29 INFO - 'TMOUT': '86400', 16:40:29 INFO - 'USER': 'cltbld', 16:40:29 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:29 INFO - '_': '/tools/buildbot/bin/python'} 16:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:29 INFO - Running setup.py (path:/tmp/pip-xoM1ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:29 INFO - Running setup.py (path:/tmp/pip-BxtgN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:29 INFO - Running setup.py (path:/tmp/pip-KE05BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:29 INFO - Running setup.py (path:/tmp/pip-KyqkZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:29 INFO - Running setup.py (path:/tmp/pip-dVUxy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:29 INFO - Running setup.py (path:/tmp/pip-bxPV1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:29 INFO - Running setup.py (path:/tmp/pip-5QLIfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:29 INFO - Running setup.py (path:/tmp/pip-lBbFz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:29 INFO - Running setup.py (path:/tmp/pip-mH5i_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:29 INFO - Running setup.py (path:/tmp/pip-23T04L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:30 INFO - Running setup.py (path:/tmp/pip-EhexUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:30 INFO - Running setup.py (path:/tmp/pip-6VvqZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:30 INFO - Running setup.py (path:/tmp/pip-3uL4XX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:30 INFO - Running setup.py (path:/tmp/pip-c57rBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:30 INFO - Running setup.py (path:/tmp/pip-P8cloo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:30 INFO - Running setup.py (path:/tmp/pip-OLqCdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:30 INFO - Running setup.py (path:/tmp/pip-QLvxBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:40:30 INFO - Running setup.py install for manifestparser 16:40:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:40:30 INFO - Running setup.py install for mozcrash 16:40:30 INFO - Running setup.py install for mozdebug 16:40:30 INFO - Running setup.py install for mozdevice 16:40:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:40:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:40:30 INFO - Running setup.py install for mozfile 16:40:31 INFO - Running setup.py install for mozhttpd 16:40:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Running setup.py install for mozinfo 16:40:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Running setup.py install for mozInstall 16:40:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Running setup.py install for mozleak 16:40:31 INFO - Running setup.py install for mozlog 16:40:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Running setup.py install for moznetwork 16:40:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Running setup.py install for mozprocess 16:40:31 INFO - Running setup.py install for mozprofile 16:40:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:40:31 INFO - Running setup.py install for mozrunner 16:40:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:40:32 INFO - Running setup.py install for mozscreenshot 16:40:32 INFO - Running setup.py install for moztest 16:40:32 INFO - Running setup.py install for mozversion 16:40:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:40:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:40:32 INFO - Cleaning up... 16:40:32 INFO - Return code: 0 16:40:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:32 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')]} 16:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:32 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')]} 16:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:32 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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:32 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 16:40:32 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 16:40:32 INFO - Using env: {'DISPLAY': ':0', 16:40:32 INFO - 'HOME': '/home/cltbld', 16:40:32 INFO - 'LANG': 'en_US.UTF-8', 16:40:32 INFO - 'LANGUAGE': 'en_US:en', 16:40:32 INFO - 'LOGNAME': 'cltbld', 16:40:32 INFO - 'MAIL': '/var/mail/cltbld', 16:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:32 INFO - 'MOZ_NO_REMOTE': '1', 16:40:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:32 INFO - 'NO_EM_RESTART': '1', 16:40:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:32 INFO - 'PWD': '/builds/slave/test', 16:40:32 INFO - 'SHELL': '/bin/bash', 16:40:32 INFO - 'SHLVL': '1', 16:40:32 INFO - 'TERM': 'linux', 16:40:32 INFO - 'TMOUT': '86400', 16:40:32 INFO - 'USER': 'cltbld', 16:40:32 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:32 INFO - '_': '/tools/buildbot/bin/python'} 16:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:32 INFO - Running setup.py (path:/tmp/pip-0KinSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:32 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)) 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:32 INFO - Running setup.py (path:/tmp/pip-D2Fo68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:32 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)) 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:32 INFO - Running setup.py (path:/tmp/pip-OvMea4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:32 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)) 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:32 INFO - Running setup.py (path:/tmp/pip-8ppLKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:32 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)) 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:32 INFO - Running setup.py (path:/tmp/pip-CzPCwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:32 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)) 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:32 INFO - Running setup.py (path:/tmp/pip-H1x1Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:32 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)) 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:32 INFO - Running setup.py (path:/tmp/pip-juwarj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:33 INFO - Running setup.py (path:/tmp/pip-a4WsIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:33 INFO - Running setup.py (path:/tmp/pip-QdS530-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:33 INFO - Running setup.py (path:/tmp/pip-CortZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:33 INFO - Running setup.py (path:/tmp/pip-2qMfmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:33 INFO - Running setup.py (path:/tmp/pip-Wh9iMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:33 INFO - Running setup.py (path:/tmp/pip-qKoBOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:33 INFO - Running setup.py (path:/tmp/pip-zkTZio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:33 INFO - Running setup.py (path:/tmp/pip-_ocNW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:33 INFO - Running setup.py (path:/tmp/pip-7drUTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:33 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)) 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:33 INFO - Running setup.py (path:/tmp/pip-kLt3y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:33 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)) 16:40:33 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)) 16:40:33 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)) 16:40:33 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)) 16:40:33 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)) 16:40:33 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)) 16:40:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:40:33 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 16:40:33 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 16:40:33 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 16:40:33 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 16:40:33 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 16:40:33 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 16:40:35 INFO - Downloading blessings-1.6.tar.gz 16:40:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:40:35 INFO - Installing collected packages: blessings 16:40:35 INFO - Running setup.py install for blessings 16:40:35 INFO - Successfully installed blessings 16:40:35 INFO - Cleaning up... 16:40:35 INFO - Return code: 0 16:40:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:40:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:40:36 INFO - Reading from file tmpfile_stdout 16:40:36 INFO - Current package versions: 16:40:36 INFO - argparse == 1.2.1 16:40:36 INFO - blessings == 1.6 16:40:36 INFO - blobuploader == 1.2.4 16:40:36 INFO - docopt == 0.6.1 16:40:36 INFO - functools32 == 3.2.3-2 16:40:36 INFO - jsonschema == 2.5.1 16:40:36 INFO - manifestparser == 1.1 16:40:36 INFO - mozInstall == 1.12 16:40:36 INFO - mozcrash == 0.17 16:40:36 INFO - mozdebug == 0.1 16:40:36 INFO - mozdevice == 0.48 16:40:36 INFO - mozfile == 1.2 16:40:36 INFO - mozhttpd == 0.7 16:40:36 INFO - mozinfo == 0.9 16:40:36 INFO - mozleak == 0.1 16:40:36 INFO - mozlog == 3.4 16:40:36 INFO - moznetwork == 0.27 16:40:36 INFO - mozprocess == 0.23 16:40:36 INFO - mozprofile == 0.28 16:40:36 INFO - mozrunner == 6.12 16:40:36 INFO - mozscreenshot == 0.1 16:40:36 INFO - mozsystemmonitor == 0.3 16:40:36 INFO - moztest == 0.7 16:40:36 INFO - mozversion == 1.4 16:40:36 INFO - psutil == 3.1.1 16:40:36 INFO - requests == 1.2.3 16:40:36 INFO - wsgiref == 0.1.2 16:40:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:36 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')]} 16:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:36 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')]} 16:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:36 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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:36 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 16:40:36 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 16:40:36 INFO - Using env: {'DISPLAY': ':0', 16:40:36 INFO - 'HOME': '/home/cltbld', 16:40:36 INFO - 'LANG': 'en_US.UTF-8', 16:40:36 INFO - 'LANGUAGE': 'en_US:en', 16:40:36 INFO - 'LOGNAME': 'cltbld', 16:40:36 INFO - 'MAIL': '/var/mail/cltbld', 16:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:36 INFO - 'MOZ_NO_REMOTE': '1', 16:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:36 INFO - 'NO_EM_RESTART': '1', 16:40:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:36 INFO - 'PWD': '/builds/slave/test', 16:40:36 INFO - 'SHELL': '/bin/bash', 16:40:36 INFO - 'SHLVL': '1', 16:40:36 INFO - 'TERM': 'linux', 16:40:36 INFO - 'TMOUT': '86400', 16:40:36 INFO - 'USER': 'cltbld', 16:40:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:36 INFO - '_': '/tools/buildbot/bin/python'} 16:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 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)) 16:40:36 INFO - Cleaning up... 16:40:36 INFO - Return code: 0 16:40:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:40:36 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')]} 16:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:36 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')]} 16:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:36 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 0x7f0c9039cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, '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': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', '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 16:40:36 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 16:40:36 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 16:40:36 INFO - Using env: {'DISPLAY': ':0', 16:40:36 INFO - 'HOME': '/home/cltbld', 16:40:36 INFO - 'LANG': 'en_US.UTF-8', 16:40:36 INFO - 'LANGUAGE': 'en_US:en', 16:40:36 INFO - 'LOGNAME': 'cltbld', 16:40:36 INFO - 'MAIL': '/var/mail/cltbld', 16:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:36 INFO - 'MOZ_NO_REMOTE': '1', 16:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:36 INFO - 'NO_EM_RESTART': '1', 16:40:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:36 INFO - 'PWD': '/builds/slave/test', 16:40:36 INFO - 'SHELL': '/bin/bash', 16:40:36 INFO - 'SHLVL': '1', 16:40:36 INFO - 'TERM': 'linux', 16:40:36 INFO - 'TMOUT': '86400', 16:40:36 INFO - 'USER': 'cltbld', 16:40:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:36 INFO - '_': '/tools/buildbot/bin/python'} 16:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:40:36 INFO - Cleaning up... 16:40:36 INFO - Return code: 0 16:40:36 INFO - Running post-action listener: _resource_record_post_action 16:40:36 INFO - Running post-action listener: _start_resource_monitoring 16:40:36 INFO - Starting resource monitoring. 16:40:36 INFO - [mozharness: 2016-10-29 23:40:36.687346Z] Finished create-virtualenv step (success) 16:40:36 INFO - [mozharness: 2016-10-29 23:40:36.687804Z] Running install step. 16:40:36 INFO - Running pre-action listener: _resource_record_pre_action 16:40:36 INFO - Running main action method: install 16:40:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:40:36 INFO - Reading from file tmpfile_stdout 16:40:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:40:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:40:36 INFO - Reading from file tmpfile_stdout 16:40:36 INFO - Output received: 16:40:36 INFO - Usage: mozinstall [options] installer 16:40:36 INFO - Options: 16:40:36 INFO - -h, --help show this help message and exit 16:40:36 INFO - -d DEST, --destination=DEST 16:40:36 INFO - Directory to install application into. [default: 16:40:36 INFO - "/builds/slave/test"] 16:40:36 INFO - --app=APP Application being installed. [default: firefox] 16:40:36 INFO - mkdir: /builds/slave/test/build/application 16:40:36 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'] 16:40:36 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 16:40:53 INFO - Reading from file tmpfile_stdout 16:40:53 INFO - Output received: 16:40:53 INFO - /builds/slave/test/build/application/firefox/firefox 16:40:53 INFO - Running post-action listener: _resource_record_post_action 16:40:53 INFO - [mozharness: 2016-10-29 23:40:53.938969Z] Finished install step (success) 16:40:53 INFO - [mozharness: 2016-10-29 23:40:53.939242Z] Running run-tests step. 16:40:53 INFO - Running pre-action listener: _resource_record_pre_action 16:40:53 INFO - Running main action method: run_tests 16:40:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:40:53 INFO - Python 2.7.3 16:40:53 INFO - Return code: 0 16:40:53 INFO - grabbing minidump binary from tooltool 16:40:53 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')]} 16:40:53 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 0x2be3f60>, '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 0x2be4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be51d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:40:53 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 16:40:53 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 16:40:53 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 16:40:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:40:54 INFO - Return code: 0 16:40:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:40:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:40:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:40:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:40:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:40:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 16:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 16:40:54 INFO - Using env: {'DISPLAY': ':0', 16:40:54 INFO - 'HOME': '/home/cltbld', 16:40:54 INFO - 'LANG': 'en_US.UTF-8', 16:40:54 INFO - 'LANGUAGE': 'en_US:en', 16:40:54 INFO - 'LOGNAME': 'cltbld', 16:40:54 INFO - 'MAIL': '/var/mail/cltbld', 16:40:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:40:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:54 INFO - 'MOZ_NO_REMOTE': '1', 16:40:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:40:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:54 INFO - 'NO_EM_RESTART': '1', 16:40:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:54 INFO - 'PWD': '/builds/slave/test', 16:40:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:40:54 INFO - 'SHELL': '/bin/bash', 16:40:54 INFO - 'SHLVL': '1', 16:40:54 INFO - 'TERM': 'linux', 16:40:54 INFO - 'TMOUT': '86400', 16:40:54 INFO - 'USER': 'cltbld', 16:40:54 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274', 16:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:54 INFO - '_': '/tools/buildbot/bin/python'} 16:40:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 16:40:54 INFO - mozversion application_buildid: 20161029123004 16:40:54 INFO - mozversion application_changeset: 40cd4eb145ffd5d0c4d698965ed3870903c70c2f 16:40:54 INFO - mozversion application_display_name: Nightly 16:40:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:40:54 INFO - mozversion application_name: Firefox 16:40:54 INFO - mozversion application_remotingname: firefox 16:40:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:40:54 INFO - mozversion application_vendor: Mozilla 16:40:54 INFO - mozversion application_version: 52.0a1 16:40:54 INFO - mozversion platform_buildid: 20161029123004 16:40:54 INFO - mozversion platform_changeset: 40cd4eb145ffd5d0c4d698965ed3870903c70c2f 16:40:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:40:54 INFO - mozversion platform_version: 52.0a1 16:40:54 INFO - using testdate: 1477784454 16:40:54 INFO - actual date: 1477784454 16:40:54 INFO - starting webserver on 'localhost:35516' 16:40:54 INFO - SUITE-START | Running 2 tests 16:40:54 INFO - TEST-START | dromaeo_css 16:40:54 INFO - operating with platform_type : linux_ 16:40:54 INFO - Initialising browser for dromaeo_css test... 16:40:54 INFO - TEST-INFO | started process 24554 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1RzXmX/profile http://localhost:35516/getInfo.html) 16:40:58 INFO - PROCESS | 24554 | __metrics Screen width/height:1600/1200 16:40:58 INFO - PROCESS | 24554 | colorDepth:24 16:40:58 INFO - PROCESS | 24554 | Browser inner width/height: 1024/697 16:40:58 INFO - PROCESS | 24554 | __metrics 16:40:58 INFO - PROCESS | 24554 | 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" 16:40:58 INFO - PROCESS | 24554 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:40:59 INFO - PROCESS | 24554 | console.error: PushService: 16:40:59 INFO - PROCESS | 24554 | stateChangeProcessEnqueue: Error transitioning state 16:40:59 INFO - PROCESS | 24554 | UnknownError 16:41:00 INFO - TEST-INFO | 24554: exit 0 16:41:00 INFO - Browser initialized. 16:41:00 INFO - Running cycle 1/1 for dromaeo_css test... 16:41:00 INFO - TEST-INFO | started process 24635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1RzXmX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:41:12 INFO - PROCESS | 24635 | RSS: Main: 174690304 16:41:12 INFO - PROCESS | 24635 | 16:42:03 INFO - PROCESS | 24635 | 1477784523677 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] 16:44:15 INFO - PROCESS | 24635 | Cycle 1(1): loaded http://localhost:35516/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35516/tests/dromaeo/cssquery-ext.html) 16:44:16 INFO - PROCESS | 24635 | RSS: Main: 293789696 16:44:16 INFO - PROCESS | 24635 | 16:47:18 INFO - PROCESS | 24635 | Cycle 1(1): loaded http://localhost:35516/tests/dromaeo/cssquery-ext.html (next: http://localhost:35516/tests/dromaeo/cssquery-jquery.html) 16:47:19 INFO - PROCESS | 24635 | RSS: Main: 279576576 16:47:19 INFO - PROCESS | 24635 | 16:50:21 INFO - PROCESS | 24635 | Cycle 1(1): loaded http://localhost:35516/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35516/tests/dromaeo/cssquery-mootools.html) 16:50:21 INFO - PROCESS | 24635 | RSS: Main: 218664960 16:50:21 INFO - PROCESS | 24635 | 16:53:23 INFO - PROCESS | 24635 | Cycle 1(1): loaded http://localhost:35516/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35516/tests/dromaeo/cssquery-prototype.html) 16:53:24 INFO - PROCESS | 24635 | RSS: Main: 234885120 16:53:24 INFO - PROCESS | 24635 | 16:56:27 INFO - PROCESS | 24635 | Cycle 1(1): loaded http://localhost:35516/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35516/tests/dromaeo/cssquery-yui.html) 16:56:27 INFO - PROCESS | 24635 | RSS: Main: 218120192 16:56:27 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | Cycle 1(1): loaded http://localhost:35516/tests/dromaeo/cssquery-yui.html (next: http://localhost:35516/tests/dromaeo/cssquery-dojo.html) 16:59:30 INFO - PROCESS | 24635 | RSS: Main: 309329920 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | __start_tp_report 16:59:30 INFO - PROCESS | 24635 | _x_x_mozilla_page_load 16:59:30 INFO - PROCESS | 24635 | _x_x_mozilla_page_load_details 16:59:30 INFO - PROCESS | 24635 | |i|pagename|runs| 16:59:30 INFO - PROCESS | 24635 | |0;dojo.html;2985.2089196362967;3015.95476067859;3019.0489995651374;3041.6806235345275;3062.4181405532945;5277;5310.973445132774;5322.893542129155;5395.811146609864;5416.837494875155;5265.052290587692;5359.839204823861;5366.06093933562;5371.81198658047;5460.208269800876;4820.493848145947;4848;4890.021995600876;4907.1657818171;4922.310876477296;4885.973945471437;4926.433460152075;4957.752112394384;4962.255661650745;5021.1213037718435;4903.63222758291;4927.359443272369;4929.038837426703;4981.72600506972;5017.874553136165;4770.499097594772;4814.398200224972;4819.301201325823;4860.3195552622665;4865.367502224688;4772.2125849234835;4834.734089625069;4859.708417494961;4860.513948605147;4860.513948605147;5313.601479889024;5325.068113080234;5361.115415956362;5367.973160134175;5383.138697808332;5669.092945128801;5682.119271512922;5684.971575142139;5686.857828554278;5688.658680479143;5618.157276408571;5760.74076666551;5794.188813566104;5802.796902108448;5821.417858214187;5283.894322113536;5293.841184764463;5368.114261146867;5375.919361209546;5439.836804895859;5840.561957853178;5881.206037184926;5893.174955506232;5893.381194974551;5893.764249430061;5633.746481408347;5647.604667673223;5648.85877853057;5649.971750141223;5651.350094739076;5522.420145884625;5598.1322894951345;5612.5229355504425;5671.886562268732;5676.262085928803;5650.26546548946;5659.377468478464;5662.6036177468;5666.943330566724;5668.26312579362;5646.520045796154;5660.037793575102;5694.316681998104;5704.258446402024;5719.7140142992685;4358.346248062816;4398.142362239332;4443.755593442327;4448.866534003985;4455.821767129279;3934.586868378779;3944.861929832442;3948.1314110896133;3986.0433495961342;3988.720789544704;5309.867253318698;5359.383670877899;5362.544183744348;5437.891242175214;5461.890762184734;5654.37801841797;5692.345380281238;5705.857353566194;5718.085106382959;5719.456651618089;5343.519083282523;5378.058839703036;5385.8114965976;5387.703676297763;5388.973055134699;4971.751412429364;5008.449070602231;5009.674371165863;5010.64925455222;5011.949880501148;5852.180694702745;5879.088741245114;5921.6743079131065;5927.081302398135;5942.821715348547;5690.601657883908;5732.828015159552;5736.856578585569;5800.651960882361;5801.100829371454;5933.584649074523;5937.524998000236;5942.494887934401;5943.732532036155;5957.6127551709;5483.835484935299;5495.697736624524;5574.358948720949;5597.076482380362;5615.410381909841;5709.7145142741865;5724.4561766632105;5733.942660573341;5767.990601644779;5828.94171058301;5591.440855914539;5604.775808967595;5610.354809196994;5613.438656134354;5613.88772224545;5479.4520547944885;5602.13166959122;5619.662820230799;5619.831405057855;5624.29696287964;5520.199571062254;5559.499645031967;5582.720863956867;5589.832305030855;5647;5649.039663257174;5652.7173641318595;5659.547236221175;5662.094064949588;5663.178839068394;5527.170924361378;5534.39121696605;5534.4742249486235;5542.8337149885565;5598.048331783688 16:59:30 INFO - PROCESS | 24635 | |1;ext.html;555.6818968330088;593.9168516407702;610.6183635227983;611.5107913669063;638.5976834594205;31446.68553314466;32035.19912002198;42555.78722106389;43886.12227755443;43973;40716.59283407165;44832.10335793282;44854.32718509225;44905;44948.32577511332;8779.253763430115;8923.910760892373;8930.821383572325;8937.64249430022;8940.73177804667;8086.555239461827;8249.83500329996;8867.645294188225;10433.739156521071;10710.678679639623;10836.783264334708;10895.182861285435;10907.018368346848;10916.235863489559;10917.508712107974;10685.145188384988;10724.892751072506;10771.138308935266;10777.299475534066;10792.406417647026;10612.787744245072;10686.305390149693;10688;10694.411807350592;10701.143908487302;42205.366919496235;42602.786986065184;42636.78681606572;42955;43043;3463.8441270142903;3527.5237842891393;3598.874039408608;3742.5508938927233;3963.663088637469;31885.521717174262;32125.678743212502;32149;32330.515042274386;33223.50164747531;4216.894577635553;4217.135487225111;4320.589543993316;4320.589543993316;4354.869353919387;363230.9999999974;364793;365613;366212;367040;59528;59908.40091599115;59978;59983;60026.699866500385;35317.47023794645;36325.455118173246;36334;38023;38041;15224.695506089816;15413.768793468107;15414.075155490706;15436.845631543765;15443.84556154424;9844.458554779412;10957.232993690524;11043.834342484708;11049.116070714485;11055.778884422267;16392.508224753277;18084.9095754523;18494.352697655788;18923;19193;6955.304469553105;6983.676791483325;7006.229314775371;7089.89365159523;7110.64446777659;895.7536677413764;980.8038392321564;1014.3558840136419;1034.5137785240925;1059.142094903131;30983.69016309853;31349.529757053664;31850.840745796122;31902.355640191163;32364.83817580944;1060.7666313411037;1088.7768007558432;1093.3221402730198;1116.665000499863;1166.0438440478895;15426.305816238297;16248.350065997227;16295;16505.504834854975;16524.173791310386;553869;558858;559470;560428;560775;55035.44964550383;55460;55800.44199558034;55806.44193558012;56311.43688563144;1209.056935590241;1239.293602646483;1243.8445194350886;1358.864113588653;1359.0894100952385;28822.567661484674;31072.844635776677;31381;32955.34089318248;34193.829030854686;53155.73422132865;53841.73079134579;53888.73055634697;53928;53999.46000539944;508421;512536;516905;517155;517520;23730.881345593163;24340;26916;26920;27317.45365092727;43599.82000900006;44727.329090064326;44816.725658407195;48379.5162048375;48977;38917;38978.6102138975;38988.2202355957;39185.41221881731;41125;39191.80404098075;39518.40722389109;39755.2048959025;40257.396139058525;41060.79469602633;38422.80788596039;38629.806850965564;39500.80249598734;39575.60424395719;39600.2079958405;32603.34793304168;35132;35276.29447411089;35603.28793424065;35785.821070894475;26898;27114.45771084607;27460.588091178248;27499.45001100007;27737.86131069332 16:59:30 INFO - PROCESS | 24635 | |2;jquery.html;371.526303962448;385.6567654787239;392.3212841783714;400.5393797133298;410.9486314210724;17642.20610072548;18740.344087956924;19404.32084877028;19432.70850937237;19675.704864427044;16992.15039248036;17788.377406790765;17817.109144542752;18264.17811198496;18921.337753178643;5648.717564121788;5890.764369425217;5896;5899.115132730082;6208.906866397007;6426.614403135827;6598.736050557972;6600.603963762165;6605.207375114993;6610.669466526679;5484.945150548503;5736.541076713853;5766.019776637961;6572.769953051644;6574.276829548746;5826.679532625704;5849.649021058707;5857.092150716624;5861.238039054895;5866.6480011199255;5462.535684466825;5620.241267428885;5657.349404818417;5664.745086471119;5668.801591944303;13047.608571742863;13266.137701049498;13728.519501817489;22324.441888952744;22709.205177818698;2316.6756654068445;2428.247243354566;2517.836340637871;2572.305477521059;2581;9329.673461428818;9590.856137157949;9603.615855365777;10024.59901603935;10100.292979491438;1795.245996681397;1870.326682394337;1892.3944337811906;1971.3001884331143;1975.3876298347413;83486.58256708739;85245.57377213136;85320;86155.56922215411;87292.56353718255;10593.311434756715;10669.626563070255;10687.572497100029;10913.56345746177;11034.834477482844;15170.31733571992;15173.317200725995;15182.620434489005;15200.315985780666;15213.163276019925;7540.585267810323;7569;7589.924100759032;7737.049499250685;7902.604869756489;7158.498905076699;7263.636818159071;7290.562566245936;7317.231690672509;7523.774286771406;4666.906661866811;4694.201985662377;4702.435707715096;4716;4740.146773580788;1908.742319786811;1911.7323574699553;1932.449251963212;1960.5098725318671;1972.806452096461;788.467784245632;893.2942975049768;906.6472503852492;950.3870003847479;1000.6947880896397;18038.819611803978;18227.63544729098;19629.411117666208;19659.311924082587;19810.306639267557;933.8012252423731;943.8914524829595;947.6351604632266;962.259060523393;1003.2726273451835;10428.426436545911;10977.444355627465;10995;11044.502997365138;11073.390963496924;438178;454888;496660;501133;501670;89270.55364723265;89553;94031.18641356195;94440.52779736195;95025;1034.6016783538391;1042.6455005298235;1081.464674985887;1103.8454616353717;1109.5728144664363;16331.673366532841;16365.181740913145;17434.912825435793;17614.383496577557;17758.91120544389;43116.13767724691;43660.781696092585;43886.56113438825;44195;45458.54541454675;53760.46239537554;56465;60419.69790151021;61731;62111.6894415525;8206;8353.874691879506;8356.6239519223;8449.281811045885;8664.783380415289;11718.355490447766;12305.876941230832;13200.207987520394;13214.603561893158;13580.25308608036;11096.667099987013;11451.25566838153;11516.481758320728;11755.588554400554;11780.234284771463;11444.828327574927;11606.361650109326;11611.477483513094;11652.94173529127;11739.354335511287;11243.66269011931;11406.258593191416;11514.984877646211;11685.532578696757;12022.158448908493;8167.632456539352;8237.258646721824;8242.793930151794;8331.95834020826;8469.02606200295;10871.728206794893;11642;11846.822297665714;12007.159498834997;12198.451069702061 16:59:30 INFO - PROCESS | 24635 | |3;mootools.html;1259.8173264876586;1443.9639009024772;1457.060196173468;1554.3112209313913;1697.5246930859364;7304.561726296426;7936;8274.462159959598;8275.255227029566;8482.872756908651;8339.666413343459;8405.285550728195;8418.158184181579;8418.49489030657;8419.579021048954;2308.365199570119;2327.150590034642;2327.3134425344565;2334.953300933989;2336.4042169246904;2281.4638559938403;2324.3840382298704;2332.3586013846243;2349.119080344871;2351.6472529120597;2231.386368748599;2250.1112060736;2270.489139943516;2283.2807665585287;2285.828562857784;2191.7808219178114;2206.249875042485;2224.143704673704;2225.6661500775012;2234.1956895517683;2246.2362796649054;2300.332903457995;2304.7695230476984;2307.8038366738847;2311.3759284992957;8041.59792010403;8338.583070846433;8409.915900840975;8489.872651910226;8491.872621910676;5116.462771409023;5235.712035838027;5245.86885327866;5248.501392367719;5250.842474725764;3374.578177727784;3442.7245820334315;3522.137076416269;3545;3550.0947258449187;1184.7097461121994;1196.36592605919;1214.623466725318;1234.2964510229174;2147.9463013424634;10634.57461701531;10886.61896833615;10894.074003709695;10895.727606809814;10899.073578745736;4338.869833904925;4351.8476853309985;4485;4557.065801510682;4598.471175814824;9187.173154416134;9189.586468608928;9192.034836342222;9193.724188274362;9200.631974720936;5244.711540865289;5245.291885595472;5247.658902171346;5249.396319423316;5250.264962905197;3883.5533913599734;4136.069384388488;4137.46212992309;4703.64722645803;4872.537108974641;3682.760620559655;3732.5520937487677;3777.0934975605655;3785.280796648628;3793.6206379361843;1129.711923459529;1173.2198088271205;1174.5477990973536;1174.7767924094567;1182.5742732616252;880.4541184465544;929.8047410043831;935.862926544252;958.1999030809213;971.3831716859845;5364.275822763877;5499.9375234287145;5519.39286678465;5615.213870058195;5622.212890195376;419.45261433828534;421.0631345256779;431.17430121317324;431.38096831047545;432.01931615233246;3498.7900725956524;3683.189698266377;3728.4034554471154;3749.9437508437395;3832.789196594214;9882.604695812088;10160.441175735405;10247.077763001365;10247.231457640557;10267.486625668687;9886.555105020292;9996.400215987063;10161.593536258615;10181.694549163536;10189.643362482277;7648.349890259279;7815.57014364204;7850.960745196352;7854.567998760009;7859.624565701094;5377.704226267437;5465.836024919259;5476.288082549243;5530.6128570999645;5547.722613869371;9160.908390916005;9287;9290.674826381044;9375;9515.33392662507;9403.38877972957;9492.57283422234;9570.425774453555;9570.95214523923;9673.758156046155;7133.2153463117975;7155.534890232118;7202.063731714844;7221.025161603116;7266.055412796302;8000.919990800017;8147.429679922348;8153.306968907472;8159.8776018358585;8172.918270817216;7090.858182836418;7222.674979625825;7237.746678866214;7253.56478611285;7374.778756637309;7056.894146587908;7086.893696594452;7211.062561866924;7233.3851622612665;7251.782446526613;6202.85247229264;6288.496920246461;6357.523185761179;6357.936420635734;6358.109864619049;4811.470738218723;4919.212925931977;4921.26181072842;4937.456879743298;5003.974980125076;4711.528847115261;4814.470408255159;4871.610271178226;4877.878053048702;4879.756012199447 16:59:30 INFO - PROCESS | 24635 | |4;prototype.html;317.92711520883836;326.7761583315429;328.15336432005427;331.8805230117156;333.7813732005537;5036.765992331874;5139.871503212422;5207.218917162423;5233.2150177473395;5253.947460525394;5011.774470148843;5078.74606269686;5121.798875702686;5140.684258653176;5153.458886816877;4096.774677392742;4121.938170927424;4122.443470131524;4127.360259159835;4133.7106402551835;3847.7306588538813;4003.619656132663;4119.629233368996;4127.711060225771;4132.297509423405;3999.480067591224;4032.8386864525382;4042.6563742081958;4061.1977735362507;4097.836086556535;4039.555648878621;4044.5348784889525;4048.311786996203;4054.0810616356175;4054.493188351456;3618.565772107364;3636.5272514573203;3641.8725344612812;3647.544056992876;3652.543432070991;4865.537773911473;4972.801087956477;4996.850094497171;5004.324416203803;5012.323336349583;4605.562471565196;4609.239475486541;4623.12160689468;4623.69945953512;4636.3045543168455;5295.68225906443;5337.386200586943;5342.839714808562;5366.195070739382;5375.274337964403;3694.2796154749835;3868.8973642512033;3869.961300386988;3871.922561548753;3900.4539364488996;5520.91718624221;5525.364583072957;5564.554835613182;5572.164175373686;5611.148321054083;3934.311495488278;3940.3301438754906;3943.1018201621787;3947.447357369913;3974.682025437958;3904.3557812960544;3928.332183528807;3939.763614183158;3968.7817170055923;4015.6346842073835;4510.774461276923;4525.728456292633;4526.343680166357;4529.5243999380245;4531.864044078683;5424.484673955968;5438.293021907206;5443.346798384219;5460.805428824182;5477.1510415885605;1299.896008319351;1300.746354460871;1381.9309034548232;1389.8123753293175;1399.3632897031825;863.3222920007764;877.2937785083069;881.4931414436725;881.7883707910183;894.2979760887672;4617.584417402382;4640.9535904640525;4642.908916404642;4646.4888862225125;4686;4803.423589169322;4830.7101573905675;4839.661223714306;4859.246816743411;4876.902461950812;4615;4618.475902952017;4627.398438203012;4627.5603817637275;4629.282461218517;2422.9394265143515;2557.399011232359;2560.8207425480036;2572.5626643470655;2595.8182927194916;4944.406671199479;4980.352554168007;4983.177775667047;5000.674956127913;5006.199008158677;4846.75766211688;4883.8290659826735;4892.951070489321;4947.876303092379;4967.155583550805;5339.359276886798;5701.800436984769;5727.828165155052;5742.397048310035;5753.654780713171;5517.2275881377445;5536.335639723259;5547.833564993057;5550.2507161534295;5583.525400341016;5262.97368513155;5472.507474327171;5524.309461317335;5549.805756798492;5549.805756798492;5745.253117094751;5776.451237132465;5783.16144159103;5787.797427090031;5796.188533605383;4008.659263962596;4014.8394064237104;4021.1153546219784;4023.637872591481;4024.436578878901;5278;5300.867478313073;5518.703104770296;5535.280413546213;5569.498745112797;5289.814856480005;5302.973485132549;5419.777891064645;5435;5446.918296225639;5328.8401347959625;5377.48913853199;5434.510894019557;5441.83674489766;5460.453954604666;5291.973540132429;5338.54622357104;5366.543843773323;5401.648892821954;5423.3491980962535;5393.9545340994255;5405.270288511001;5406.135018397038;5408.72956352173;5441.646292990943;5320.680759154463;5325.017989925654;5359.678419294854;5361.919571206513;5363.678179309254 16:59:30 INFO - PROCESS | 24635 | |5;yui.html;766.0194950463406;767.2787579675107;768.642581199742;789.3763926498067;811.4279432999729;14500.347484363203;15226.543203703906;15244.237788110606;15245.39018439261;15245.466408675698;13867.722645547083;14036;14054.718905621881;14143.222122783269;14151;630.8296759874831;647.0003844060938;651.9934800652004;773.3117525402396;787.3504358903898;650.8535579494624;653.6927644007333;753.2655660730799;783.9294463498284;784.8901153838467;831.5094094484253;833.5373867503533;838.6687258532876;839.3788596438652;842.2630198576246;831.3598529132595;831.7731346264272;831.9625616847226;832.4173078844802;832.4381042796118;491.70743407672376;501.53858450225516;581.8050952930773;600.5135839969638;602.2381687165739;13368.33158342079;13400.128991615515;13410.46358145673;13422;13478.797818032737;12514;12697.174683645533;13232.536861209908;13293.86706132936;13315.667108322272;11738.823917641243;11749.177557570973;11781.234219775688;11814.527418903233;11848.763024739543;4121.67722617171;4155.023569461174;4159.958400415987;4222.577742225783;4236.491621005468;60876.69561652163;61490.69254653743;62650;63317.050244246835;65272;9723.124918757347;9751.512424378752;10013.449260290608;10064.14454771338;10530.420826854443;19805.40583782489;19938.800611993986;19987.900060499604;20115.497112572302;20288.797112028988;7690.884636730453;7804;7804.219578042151;7883.378448853878;8185.71350002747;4282.978585107055;4320;4324.437823082979;4444.377787109743;4447.466304043535;3259.08745551246;3322.534845121685;3325.6840600142946;3348;3350.145712843258;572.816698656426;573.761888816145;583.201014609987;589.949854262384;592.3129170162591;660.1121491593799;676.6582875647865;681.4326998164604;695.2838576266454;710.9289071092953;7979.4015448841565;8121.309688676411;8447.746567602982;8555.486670799772;8756.168164024408;545.6889572943384;611.8042226487481;645.2901808011244;668.3383450384084;685.8148299958983;4067.7152599317765;4161.604647558477;4386.1752072719755;4439.933400998988;4452.243118669834;84469;88798.55600721955;90707;92110;93125;78018;78185.60907195427;78908;79013;80613.59693201496;65851.34148658547;65873.01190482147;66432.6678366605;70347.2965270351;71651.28348716446;8326.83346333082;8643.654253829776;8792.648294068422;8888.33337499703;9165.633374664938;41780.373294401215;41826.516889882114;43680.56319436892;45735;45837.31244031276;42223.15553688847;42541;55106.72446637741;61602.38397616146;65293.34706653063;11828.349440780496;11872.228305160137;12020.398980050788;12330.445129968994;12744.808827867775;12611.81082283748;12644.936775316433;13364.599062028155;13409.396577154246;13689.452421903012;11234.943825281149;11415.37215453158;11556.711082223012;11772.234804737665;11906.04751619851;9211.539423028687;12236.632901012983;12276.81584776247;12341.814872776737;12667.176633518791;11702;12123.272603643456;12609.810852837032;13034.608961731163;13275.66810829737;9640.276979226446;9685.923774087842;9732.951335243562;9898.158656514277;10727.302725322828;9846.359986600848;10262.538185781807;10361.533730982275;10376.497233765678;10414.635487757892 16:59:30 INFO - PROCESS | 24635 | __end_tp_report 16:59:30 INFO - PROCESS | 24635 | __start_cc_report 16:59:30 INFO - PROCESS | 24635 | _x_x_mozilla_cycle_collect,1170 16:59:30 INFO - PROCESS | 24635 | __end_cc_report 16:59:30 INFO - PROCESS | 24635 | __startTimestamp1477785570936__endTimestamp 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | ------- Summary: start ------- 16:59:30 INFO - PROCESS | 24635 | Number of tests: 6 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | [#0] dojo.html Cycles:165 Average:5319.50 Median:5534.47 stddev:599.83 (10.8%) stddev-sans-first:573.04 16:59:30 INFO - PROCESS | 24635 | Values: 2985.2 3016.0 3019.0 3041.7 3062.4 5277.0 5311.0 5322.9 5395.8 5416.8 5265.1 5359.8 5366.1 5371.8 5460.2 4820.5 4848.0 4890.0 4907.2 4922.3 4886.0 4926.4 4957.8 4962.3 5021.1 4903.6 4927.4 4929.0 4981.7 5017.9 4770.5 4814.4 4819.3 4860.3 4865.4 4772.2 4834.7 4859.7 4860.5 4860.5 5313.6 5325.1 5361.1 5368.0 5383.1 5669.1 5682.1 5685.0 5686.9 5688.7 5618.2 5760.7 5794.2 5802.8 5821.4 5283.9 5293.8 5368.1 5375.9 5439.8 5840.6 5881.2 5893.2 5893.4 5893.8 5633.7 5647.6 5648.9 5650.0 5651.4 5522.4 5598.1 5612.5 5671.9 5676.3 5650.3 5659.4 5662.6 5666.9 5668.3 5646.5 5660.0 5694.3 5704.3 5719.7 4358.3 4398.1 4443.8 4448.9 4455.8 3934.6 3944.9 3948.1 3986.0 3988.7 5309.9 5359.4 5362.5 5437.9 5461.9 5654.4 5692.3 5705.9 5718.1 5719.5 5343.5 5378.1 5385.8 5387.7 5389.0 4971.8 5008.4 5009.7 5010.6 5011.9 5852.2 5879.1 5921.7 5927.1 5942.8 5690.6 5732.8 5736.9 5800.7 5801.1 5933.6 5937.5 5942.5 5943.7 5957.6 5483.8 5495.7 5574.4 5597.1 5615.4 5709.7 5724.5 5733.9 5768.0 5828.9 5591.4 5604.8 5610.4 5613.4 5613.9 5479.5 5602.1 5619.7 5619.8 5624.3 5520.2 5559.5 5582.7 5589.8 5647.0 5649.0 5652.7 5659.5 5662.1 5663.2 5527.2 5534.4 5534.5 5542.8 5598.0 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | [#1] ext.html Cycles:180 Average:62463.83 Median:366626.00 stddev:129480.61 (35.3%) stddev-sans-first:129760.40 16:59:30 INFO - PROCESS | 24635 | Values: 555.7 593.9 610.6 611.5 638.6 31446.7 32035.2 42555.8 43886.1 43973.0 40716.6 44832.1 44854.3 44905.0 44948.3 8779.3 8923.9 8930.8 8937.6 8940.7 8086.6 8249.8 8867.6 10433.7 10710.7 10836.8 10895.2 10907.0 10916.2 10917.5 10685.1 10724.9 10771.1 10777.3 10792.4 10612.8 10686.3 10688.0 10694.4 10701.1 42205.4 42602.8 42636.8 42955.0 43043.0 3463.8 3527.5 3598.9 3742.6 3963.7 31885.5 32125.7 32149.0 32330.5 33223.5 4216.9 4217.1 4320.6 4320.6 4354.9 363231.0 364793.0 365613.0 366212.0 367040.0 59528.0 59908.4 59978.0 59983.0 60026.7 35317.5 36325.5 36334.0 38023.0 38041.0 15224.7 15413.8 15414.1 15436.8 15443.8 9844.5 10957.2 11043.8 11049.1 11055.8 16392.5 18084.9 18494.4 18923.0 19193.0 6955.3 6983.7 7006.2 7089.9 7110.6 895.8 980.8 1014.4 1034.5 1059.1 30983.7 31349.5 31850.8 31902.4 32364.8 1060.8 1088.8 1093.3 1116.7 1166.0 15426.3 16248.4 16295.0 16505.5 16524.2 553869.0 558858.0 559470.0 560428.0 560775.0 55035.4 55460.0 55800.4 55806.4 56311.4 1209.1 1239.3 1243.8 1358.9 1359.1 28822.6 31072.8 31381.0 32955.3 34193.8 53155.7 53841.7 53888.7 53928.0 53999.5 508421.0 512536.0 516905.0 517155.0 517520.0 23730.9 24340.0 26916.0 26920.0 27317.5 43599.8 44727.3 44816.7 48379.5 48977.0 38917.0 38978.6 38988.2 39185.4 41125.0 39191.8 39518.4 39755.2 40257.4 41060.8 38422.8 38629.8 39500.8 39575.6 39600.2 32603.3 35132.0 35276.3 35603.3 35785.8 26898.0 27114.5 27460.6 27499.5 27737.9 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | [#2] jquery.html Cycles:180 Average:28649.53 Median:388.99 stddev:79243.37 (20371.6%) stddev-sans-first:79437.22 16:59:30 INFO - PROCESS | 24635 | Values: 371.5 385.7 392.3 400.5 410.9 17642.2 18740.3 19404.3 19432.7 19675.7 16992.2 17788.4 17817.1 18264.2 18921.3 5648.7 5890.8 5896.0 5899.1 6208.9 6426.6 6598.7 6600.6 6605.2 6610.7 5484.9 5736.5 5766.0 6572.8 6574.3 5826.7 5849.6 5857.1 5861.2 5866.6 5462.5 5620.2 5657.3 5664.7 5668.8 13047.6 13266.1 13728.5 22324.4 22709.2 2316.7 2428.2 2517.8 2572.3 2581.0 9329.7 9590.9 9603.6 10024.6 10100.3 1795.2 1870.3 1892.4 1971.3 1975.4 83486.6 85245.6 85320.0 86155.6 87292.6 10593.3 10669.6 10687.6 10913.6 11034.8 15170.3 15173.3 15182.6 15200.3 15213.2 7540.6 7569.0 7589.9 7737.0 7902.6 7158.5 7263.6 7290.6 7317.2 7523.8 4666.9 4694.2 4702.4 4716.0 4740.1 1908.7 1911.7 1932.4 1960.5 1972.8 788.5 893.3 906.6 950.4 1000.7 18038.8 18227.6 19629.4 19659.3 19810.3 933.8 943.9 947.6 962.3 1003.3 10428.4 10977.4 10995.0 11044.5 11073.4 438178.0 454888.0 496660.0 501133.0 501670.0 89270.6 89553.0 94031.2 94440.5 95025.0 1034.6 1042.6 1081.5 1103.8 1109.6 16331.7 16365.2 17434.9 17614.4 17758.9 43116.1 43660.8 43886.6 44195.0 45458.5 53760.5 56465.0 60419.7 61731.0 62111.7 8206.0 8353.9 8356.6 8449.3 8664.8 11718.4 12305.9 13200.2 13214.6 13580.3 11096.7 11451.3 11516.5 11755.6 11780.2 11444.8 11606.4 11611.5 11652.9 11739.4 11243.7 11406.3 11515.0 11685.5 12022.2 8167.6 8237.3 8242.8 8332.0 8469.0 10871.7 11642.0 11846.8 12007.2 12198.5 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | [#3] mootools.html Cycles:180 Average:5433.64 Median:4970.72 stddev:3011.53 (60.6%) stddev-sans-first:3003.64 16:59:30 INFO - PROCESS | 24635 | Values: 1259.8 1444.0 1457.1 1554.3 1697.5 7304.6 7936.0 8274.5 8275.3 8482.9 8339.7 8405.3 8418.2 8418.5 8419.6 2308.4 2327.2 2327.3 2335.0 2336.4 2281.5 2324.4 2332.4 2349.1 2351.6 2231.4 2250.1 2270.5 2283.3 2285.8 2191.8 2206.2 2224.1 2225.7 2234.2 2246.2 2300.3 2304.8 2307.8 2311.4 8041.6 8338.6 8409.9 8489.9 8491.9 5116.5 5235.7 5245.9 5248.5 5250.8 3374.6 3442.7 3522.1 3545.0 3550.1 1184.7 1196.4 1214.6 1234.3 2147.9 10634.6 10886.6 10894.1 10895.7 10899.1 4338.9 4351.8 4485.0 4557.1 4598.5 9187.2 9189.6 9192.0 9193.7 9200.6 5244.7 5245.3 5247.7 5249.4 5250.3 3883.6 4136.1 4137.5 4703.6 4872.5 3682.8 3732.6 3777.1 3785.3 3793.6 1129.7 1173.2 1174.5 1174.8 1182.6 880.5 929.8 935.9 958.2 971.4 5364.3 5499.9 5519.4 5615.2 5622.2 419.5 421.1 431.2 431.4 432.0 3498.8 3683.2 3728.4 3749.9 3832.8 9882.6 10160.4 10247.1 10247.2 10267.5 9886.6 9996.4 10161.6 10181.7 10189.6 7648.3 7815.6 7851.0 7854.6 7859.6 5377.7 5465.8 5476.3 5530.6 5547.7 9160.9 9287.0 9290.7 9375.0 9515.3 9403.4 9492.6 9570.4 9571.0 9673.8 7133.2 7155.5 7202.1 7221.0 7266.1 8000.9 8147.4 8153.3 8159.9 8172.9 7090.9 7222.7 7237.7 7253.6 7374.8 7056.9 7086.9 7211.1 7233.4 7251.8 6202.9 6288.5 6357.5 6357.9 6358.1 4811.5 4919.2 4921.3 4937.5 5004.0 4711.5 4814.5 4871.6 4877.9 4879.8 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | [#4] prototype.html Cycles:180 Average:4452.99 Median:4957.52 stddev:1307.06 (26.4%) stddev-sans-first:1273.34 16:59:30 INFO - PROCESS | 24635 | Values: 317.9 326.8 328.2 331.9 333.8 5036.8 5139.9 5207.2 5233.2 5253.9 5011.8 5078.7 5121.8 5140.7 5153.5 4096.8 4121.9 4122.4 4127.4 4133.7 3847.7 4003.6 4119.6 4127.7 4132.3 3999.5 4032.8 4042.7 4061.2 4097.8 4039.6 4044.5 4048.3 4054.1 4054.5 3618.6 3636.5 3641.9 3647.5 3652.5 4865.5 4972.8 4996.9 5004.3 5012.3 4605.6 4609.2 4623.1 4623.7 4636.3 5295.7 5337.4 5342.8 5366.2 5375.3 3694.3 3868.9 3870.0 3871.9 3900.5 5520.9 5525.4 5564.6 5572.2 5611.1 3934.3 3940.3 3943.1 3947.4 3974.7 3904.4 3928.3 3939.8 3968.8 4015.6 4510.8 4525.7 4526.3 4529.5 4531.9 5424.5 5438.3 5443.3 5460.8 5477.2 1299.9 1300.7 1381.9 1389.8 1399.4 863.3 877.3 881.5 881.8 894.3 4617.6 4641.0 4642.9 4646.5 4686.0 4803.4 4830.7 4839.7 4859.2 4876.9 4615.0 4618.5 4627.4 4627.6 4629.3 2422.9 2557.4 2560.8 2572.6 2595.8 4944.4 4980.4 4983.2 5000.7 5006.2 4846.8 4883.8 4893.0 4947.9 4967.2 5339.4 5701.8 5727.8 5742.4 5753.7 5517.2 5536.3 5547.8 5550.3 5583.5 5263.0 5472.5 5524.3 5549.8 5549.8 5745.3 5776.5 5783.2 5787.8 5796.2 4008.7 4014.8 4021.1 4023.6 4024.4 5278.0 5300.9 5518.7 5535.3 5569.5 5289.8 5303.0 5419.8 5435.0 5446.9 5328.8 5377.5 5434.5 5441.8 5460.5 5292.0 5338.5 5366.5 5401.6 5423.3 5394.0 5405.3 5406.1 5408.7 5441.6 5320.7 5325.0 5359.7 5361.9 5363.7 16:59:30 INFO - PROCESS | 24635 | 16:59:30 INFO - PROCESS | 24635 | [#5] yui.html Cycles:180 Average:17303.74 Median:523.61 stddev:23406.75 (4470.2%) stddev-sans-first:23439.47 16:59:30 INFO - PROCESS | 24635 | Values: 766.0 767.3 768.6 789.4 811.4 14500.3 15226.5 15244.2 15245.4 15245.5 13867.7 14036.0 14054.7 14143.2 14151.0 630.8 647.0 652.0 773.3 787.4 650.9 653.7 753.3 783.9 784.9 831.5 833.5 838.7 839.4 842.3 831.4 831.8 832.0 832.4 832.4 491.7 501.5 581.8 600.5 602.2 13368.3 13400.1 13410.5 13422.0 13478.8 12514.0 12697.2 13232.5 13293.9 13315.7 11738.8 11749.2 11781.2 11814.5 11848.8 4121.7 4155.0 4160.0 4222.6 4236.5 60876.7 61490.7 62650.0 63317.1 65272.0 9723.1 9751.5 10013.4 10064.1 10530.4 19805.4 19938.8 19987.9 20115.5 20288.8 7690.9 7804.0 7804.2 7883.4 8185.7 4283.0 4320.0 4324.4 4444.4 4447.5 3259.1 3322.5 3325.7 3348.0 3350.1 572.8 573.8 583.2 589.9 592.3 660.1 676.7 681.4 695.3 710.9 7979.4 8121.3 8447.7 8555.5 8756.2 545.7 611.8 645.3 668.3 685.8 4067.7 4161.6 4386.2 4439.9 4452.2 84469.0 88798.6 90707.0 92110.0 93125.0 78018.0 78185.6 78908.0 79013.0 80613.6 65851.3 65873.0 66432.7 70347.3 71651.3 8326.8 8643.7 8792.6 8888.3 9165.6 41780.4 41826.5 43680.6 45735.0 45837.3 42223.2 42541.0 55106.7 61602.4 65293.3 11828.3 11872.2 12020.4 12330.4 12744.8 12611.8 12644.9 13364.6 13409.4 13689.5 11234.9 11415.4 11556.7 11772.2 11906.0 9211.5 12236.6 12276.8 12341.8 12667.2 11702.0 12123.3 12609.8 13034.6 13275.7 9640.3 9685.9 9733.0 9898.2 10727.3 9846.4 10262.5 10361.5 10376.5 10414.6 16:59:30 INFO - PROCESS | 24635 | -------- Summary: end -------- 16:59:30 INFO - PROCESS | 24635 | 16:59:31 INFO - TEST-INFO | 24635: exit 0 16:59:31 INFO - TEST-OK | dromaeo_css | took 1117624ms 16:59:31 INFO - TEST-START | kraken 16:59:31 INFO - operating with platform_type : linux_ 16:59:31 INFO - Initialising browser for kraken test... 16:59:31 INFO - TEST-INFO | started process 24944 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmehSDS/profile http://localhost:35516/getInfo.html) 16:59:35 INFO - PROCESS | 24944 | __metrics Screen width/height:1600/1200 16:59:35 INFO - PROCESS | 24944 | colorDepth:24 16:59:35 INFO - PROCESS | 24944 | Browser inner width/height: 1024/697 16:59:35 INFO - PROCESS | 24944 | __metrics 16:59:36 INFO - PROCESS | 24944 | 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" 16:59:36 INFO - PROCESS | 24944 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:59:36 INFO - PROCESS | 24944 | console.error: 16:59:36 INFO - PROCESS | 24944 | Message: Error: Connection closed before committing the transaction. 16:59:36 INFO - PROCESS | 24944 | Stack: 16:59:36 INFO - PROCESS | 24944 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 16:59:36 INFO - PROCESS | 24944 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 16:59:36 INFO - PROCESS | 24944 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 16:59:36 INFO - PROCESS | 24944 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 16:59:36 INFO - PROCESS | 24944 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 16:59:36 INFO - PROCESS | 24944 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 16:59:36 INFO - PROCESS | 24944 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 16:59:36 INFO - PROCESS | 24944 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 16:59:36 INFO - PROCESS | 24944 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 16:59:36 INFO - PROCESS | 24944 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 16:59:36 INFO - PROCESS | 24944 | Barrier/this.client.addBlocker/promise13.0% 17:00:34 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 17:00:34 INFO - TinderboxPrint: I/O write bytes / time
406,503,424 / 255,184 17:00:34 INFO - TinderboxPrint: CPU idle
8,304.1 (86.9%) 17:00:34 INFO - TinderboxPrint: CPU user
1,195.0 (12.5%) 17:00:34 INFO - TinderboxPrint: Swap in / out
0 / 0 17:00:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 104001536; Read time: 0; Write time: 88036 17:00:34 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 287404032; Read time: 0; Write time: 159116 17:00:34 INFO - Running post-run listener: _upload_blobber_files 17:00:34 INFO - Blob upload gear active. 17:00:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:00:34 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 17:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:00:35 INFO - (blobuploader) - INFO - Open directory for files ... 17:00:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:00:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:00:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:38 INFO - (blobuploader) - INFO - Done attempting. 17:00:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:00:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:39 INFO - (blobuploader) - INFO - Done attempting. 17:00:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:00:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:00:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:40 INFO - (blobuploader) - INFO - Done attempting. 17:00:40 INFO - (blobuploader) - INFO - Iteration through files over. 17:00:40 INFO - Return code: 0 17:00:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:00:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:00:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a7c854c41c6f3c6a5183d17b9aec12d96a6fdd382cf4a26fe7ae22edb8ce88de5581f8ca7336db5a0404829052d076af6a2c38d03859debb1e9849a08b315715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f842ef41e6916f7464c65cab3856b2dbf63fd89b38dcbd8b901eb331961834a2805a643f4ed3775bd801c85a029c3610942ef7fd17f2c1c0174103e493b8e705", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/73123ce20a46ebe0b1352a7c4c04035d2ffc133feab48c57075f7995cdb76b4df102617ec2bdd511d9d8d764d7c397d3f530c8c71e3932b359558395395fe940"} 17:00:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:00:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:00:40 INFO - Contents: 17:00:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a7c854c41c6f3c6a5183d17b9aec12d96a6fdd382cf4a26fe7ae22edb8ce88de5581f8ca7336db5a0404829052d076af6a2c38d03859debb1e9849a08b315715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f842ef41e6916f7464c65cab3856b2dbf63fd89b38dcbd8b901eb331961834a2805a643f4ed3775bd801c85a029c3610942ef7fd17f2c1c0174103e493b8e705", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/73123ce20a46ebe0b1352a7c4c04035d2ffc133feab48c57075f7995cdb76b4df102617ec2bdd511d9d8d764d7c397d3f530c8c71e3932b359558395395fe940"} 17:00:40 INFO - Running post-run listener: copy_logs_to_upload_dir 17:00:40 INFO - Copying logs to upload dir... 17:00:40 INFO - mkdir: /builds/slave/test/build/upload/logs 17:00:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.349397 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-10-29 17:00:40.290701) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 17:00:40.294909) ========= 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=41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a7c854c41c6f3c6a5183d17b9aec12d96a6fdd382cf4a26fe7ae22edb8ce88de5581f8ca7336db5a0404829052d076af6a2c38d03859debb1e9849a08b315715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f842ef41e6916f7464c65cab3856b2dbf63fd89b38dcbd8b901eb331961834a2805a643f4ed3775bd801c85a029c3610942ef7fd17f2c1c0174103e493b8e705", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/73123ce20a46ebe0b1352a7c4c04035d2ffc133feab48c57075f7995cdb76b4df102617ec2bdd511d9d8d764d7c397d3f530c8c71e3932b359558395395fe940"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011346 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1477769404/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a7c854c41c6f3c6a5183d17b9aec12d96a6fdd382cf4a26fe7ae22edb8ce88de5581f8ca7336db5a0404829052d076af6a2c38d03859debb1e9849a08b315715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f842ef41e6916f7464c65cab3856b2dbf63fd89b38dcbd8b901eb331961834a2805a643f4ed3775bd801c85a029c3610942ef7fd17f2c1c0174103e493b8e705", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/73123ce20a46ebe0b1352a7c4c04035d2ffc133feab48c57075f7995cdb76b4df102617ec2bdd511d9d8d764d7c397d3f530c8c71e3932b359558395395fe940"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 17:00:40.320811) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 17:00:40.321174) ========= 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=41a7e32711b8e08598a63237000001d6-1477784400.560479-798267274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 17:00:40.404077) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 17:00:40.404592) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 17:00:40.404936) ========= ========= Total master_lag: 0.17 =========