builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-077 starttime: 1487598371.59 results: success (0) revision: 24931c1b6d9e5c0b1e496a2428f2012428715956 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.586767) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.587190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.587449) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.601269) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.601529) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 05:46:11-- 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% 224M=0s 2017-02-20 05:46:11 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119524 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.733857) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.734156) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014592 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:11.783578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 05:46:11.783913) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 24931c1b6d9e5c0b1e496a2428f2012428715956 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 24931c1b6d9e5c0b1e496a2428f2012428715956 --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=20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 05:46:11,988 truncating revision to first 12 chars 2017-02-20 05:46:11,988 Setting DEBUG logging. 2017-02-20 05:46:11,988 attempt 1/10 2017-02-20 05:46:11,989 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/24931c1b6d9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 05:46:13,282 unpacking tar archive at: mozilla-central-24931c1b6d9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.607327 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 05:46:13.403805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:13.404136) ========= script_repo_revision: 24931c1b6d9e5c0b1e496a2428f2012428715956 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:13.404484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:13.404782) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 05:46:13.412100) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-20 05:46:13.412391) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:46:13 INFO - MultiFileLogger online at 20170220 05:46:13 in /builds/slave/test 05:46:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:46:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:46:13 INFO - {'append_to_log': False, 05:46:13 INFO - 'base_work_dir': '/builds/slave/test', 05:46:13 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:46:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:46:13 INFO - 'branch': 'Firefox-Non-PGO', 05:46:13 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:13 INFO - 'config_files': ('talos/linux_config.py',), 05:46:13 INFO - 'default_actions': ('clobber', 05:46:13 INFO - 'read-buildbot-config', 05:46:13 INFO - 'download-and-extract', 05:46:13 INFO - 'populate-webroot', 05:46:13 INFO - 'create-virtualenv', 05:46:13 INFO - 'install', 05:46:13 INFO - 'run-tests'), 05:46:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:13 INFO - 'download_minidump_stackwalk': True, 05:46:13 INFO - 'download_symbols': 'ondemand', 05:46:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:13 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:13 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:13 INFO - 'gecko_profile': False, 05:46:13 INFO - 'gecko_profile_interval': 0, 05:46:13 INFO - 'installer_path': 'installer.exe', 05:46:13 INFO - 'log_level': 'info', 05:46:13 INFO - 'log_name': 'talos', 05:46:13 INFO - 'log_to_console': True, 05:46:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:46:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:46:13 INFO - 'opt_config_files': (), 05:46:13 INFO - 'pip_index': False, 05:46:13 INFO - 'suite': 'dromaeojs-e10s', 05:46:13 INFO - 'system_bits': '32', 05:46:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:13 INFO - 'title': 'talos-linux64-ix-077', 05:46:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:13 INFO - 'use_talos_json': True, 05:46:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:46:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:13 INFO - 'work_dir': 'build'} 05:46:13 INFO - [mozharness: 2017-02-20 13:46:13.713395Z] Running clobber step. 05:46:13 INFO - Running pre-action listener: _resource_record_pre_action 05:46:13 INFO - Running main action method: clobber 05:46:13 INFO - rmtree: /builds/slave/test/build 05:46:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:46:14 INFO - Running post-action listener: _resource_record_post_action 05:46:14 INFO - [mozharness: 2017-02-20 13:46:14.639857Z] Finished clobber step (success) 05:46:14 INFO - [mozharness: 2017-02-20 13:46:14.639956Z] Running read-buildbot-config step. 05:46:14 INFO - Running pre-action listener: _resource_record_pre_action 05:46:14 INFO - Running main action method: read_buildbot_config 05:46:14 INFO - Using buildbot properties: 05:46:14 INFO - { 05:46:14 INFO - "project": "", 05:46:14 INFO - "product": "firefox", 05:46:14 INFO - "who": "cbook@mozilla.com", 05:46:14 INFO - "installer_path": "public/build/target.tar.bz2", 05:46:14 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:46:14 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:46:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:46:14 INFO - "stage_platform": "linux64", 05:46:14 INFO - "basedir": "/builds/slave/test", 05:46:14 INFO - "buildnumber": 427, 05:46:14 INFO - "platform": "ubuntu64_hw", 05:46:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:46:14 INFO - "slavebuilddir": "test", 05:46:14 INFO - "taskId": "XxA8ngkqSAOhiEbIe5SqXw", 05:46:14 INFO - "branch": "mozilla-central", 05:46:14 INFO - "script_repo_revision": "production", 05:46:14 INFO - "revision": "24931c1b6d9e5c0b1e496a2428f2012428715956", 05:46:14 INFO - "slavename": "talos-linux64-ix-077", 05:46:14 INFO - "repo_path": "mozilla-central" 05:46:14 INFO - } 05:46:14 INFO - Finding installer, test and symbols from parent task. 05:46:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XxA8ngkqSAOhiEbIe5SqXw'}, attempt #1 05:46:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XxA8ngkqSAOhiEbIe5SqXw'}, attempt #1 05:46:15 INFO - Task dependencies: fthK8qOgSiis2UNk5Rmg8A 05:46:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A'}, attempt #1 05:46:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2 05:46:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/test_packages.json 05:46:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.crashreporter-symbols.zip 05:46:16 INFO - Running post-action listener: _resource_record_post_action 05:46:16 INFO - [mozharness: 2017-02-20 13:46:16.016558Z] Finished read-buildbot-config step (success) 05:46:16 INFO - [mozharness: 2017-02-20 13:46:16.016776Z] Running download-and-extract step. 05:46:16 INFO - Running pre-action listener: _resource_record_pre_action 05:46:16 INFO - Running main action method: download_and_extract 05:46:16 INFO - mkdir: /builds/slave/test/build/tests 05:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:16 INFO - trying https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.test_packages.json 05:46:16 INFO - Downloading https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:46:16 INFO - Downloaded 896 bytes. 05:46:16 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:46:16 INFO - Using the following test package requirements: 05:46:16 INFO - {u'common': [u'target.common.tests.zip'], 05:46:16 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:46:16 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:46:16 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:46:16 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:46:16 INFO - u'mozbase': [u'target.common.tests.zip'], 05:46:16 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:46:16 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:46:16 INFO - u'web-platform': [u'target.common.tests.zip', 05:46:16 INFO - u'target.web-platform.tests.zip'], 05:46:16 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:46:16 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:46:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.common.tests.zip 05:46:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:46:16 INFO - Fetch https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.common.tests.zip into memory 05:46:17 INFO - Expected file size: 57165260 05:46:17 INFO - Obtained file size: 57165260 05:46:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:46:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.common.tests.zip 05:46:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:46:27 INFO - Fetch https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.common.tests.zip into memory 05:46:28 INFO - Expected file size: 57165260 05:46:28 INFO - Obtained file size: 57165260 05:46:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.talos.tests.zip 05:46:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:46:38 INFO - Fetch https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.talos.tests.zip into memory 05:46:39 INFO - Expected file size: 14404319 05:46:39 INFO - Obtained file size: 14404319 05:46:39 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')]} 05:46:39 INFO - trying https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2 05:46:39 INFO - Downloading https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:46:40 INFO - Downloaded 61526973 bytes. 05:46:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2 05:46:40 INFO - mkdir: /builds/slave/test/properties 05:46:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:46:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:46:40 INFO - Contents: 05:46:40 INFO - build_url:https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2 05:46:40 INFO - Running post-action listener: _resource_record_post_action 05:46:40 INFO - Running post-action listener: set_extra_try_arguments 05:46:40 INFO - [mozharness: 2017-02-20 13:46:40.805233Z] Finished download-and-extract step (success) 05:46:40 INFO - [mozharness: 2017-02-20 13:46:40.805329Z] Running populate-webroot step. 05:46:40 INFO - Running pre-action listener: _resource_record_pre_action 05:46:40 INFO - Running main action method: populate_webroot 05:46:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:40 INFO - '--tppagecycles', 05:46:40 INFO - '7'], 05:46:40 INFO - 'tests': ['tsvgm']}}, 05:46:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:46:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:46:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:46:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:46:40 INFO - 'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:46:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:46:40 INFO - 'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['damp', 'tps']}, 05:46:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:46:40 INFO - 'tests': ['damp', 'tps']}, 05:46:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['dromaeo_dom']}, 05:46:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['basic_compositor_video']}, 05:46:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:46:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['a11yr', 05:46:40 INFO - 'ts_paint', 05:46:40 INFO - 'tpaint', 05:46:40 INFO - 'sessionrestore', 05:46:40 INFO - 'sessionrestore_no_auto_restore', 05:46:40 INFO - 'tabpaint']}, 05:46:40 INFO - 'other-e10s': {'tests': ['a11yr', 05:46:40 INFO - 'ts_paint', 05:46:40 INFO - 'tpaint', 05:46:40 INFO - 'sessionrestore', 05:46:40 INFO - 'sessionrestore_no_auto_restore', 05:46:40 INFO - 'tabpaint']}, 05:46:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:46:40 INFO - 'ts_paint', 05:46:40 INFO - 'tpaint', 05:46:40 INFO - 'sessionrestore', 05:46:40 INFO - 'sessionrestore_no_auto_restore', 05:46:40 INFO - 'tabpaint']}, 05:46:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:46:40 INFO - 'ts_paint', 05:46:40 INFO - 'tpaint', 05:46:40 INFO - 'sessionrestore', 05:46:40 INFO - 'sessionrestore_no_auto_restore', 05:46:40 INFO - 'tabpaint']}, 05:46:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['a11yr', 05:46:40 INFO - 'ts_paint', 05:46:40 INFO - 'tpaint', 05:46:40 INFO - 'sessionrestore', 05:46:40 INFO - 'sessionrestore_no_auto_restore', 05:46:40 INFO - 'tabpaint']}, 05:46:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['a11yr', 05:46:40 INFO - 'ts_paint', 05:46:40 INFO - 'tpaint', 05:46:40 INFO - 'sessionrestore', 05:46:40 INFO - 'sessionrestore_no_auto_restore', 05:46:40 INFO - 'tabpaint']}, 05:46:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['tsvgx', 05:46:40 INFO - 'tsvgr_opacity', 05:46:40 INFO - 'tart', 05:46:40 INFO - 'tscrollx', 05:46:40 INFO - 'cart', 05:46:40 INFO - 'tsvg_static']}, 05:46:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:46:40 INFO - 'tsvgr_opacity', 05:46:40 INFO - 'tart', 05:46:40 INFO - 'tscrollx', 05:46:40 INFO - 'cart', 05:46:40 INFO - 'tsvg_static']}, 05:46:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:46:40 INFO - 'talos_options': ['--disable-e10s'], 05:46:40 INFO - 'tests': ['tp5o']}, 05:46:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:46:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:46:40 INFO - 'talos_options': ['--disable-e10s', 05:46:40 INFO - '--xperf_path', 05:46:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:40 INFO - 'tests': ['tp5n']}, 05:46:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:46:40 INFO - 'talos_options': ['--xperf_path', 05:46:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:40 INFO - 'tests': ['tp5n']}}, 05:46:40 INFO - 'talos.zip': {'path': '', 05:46:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:40 INFO - Running post-action listener: _resource_record_post_action 05:46:40 INFO - [mozharness: 2017-02-20 13:46:40.818398Z] Finished populate-webroot step (success) 05:46:40 INFO - [mozharness: 2017-02-20 13:46:40.818487Z] Running create-virtualenv step. 05:46:40 INFO - Running pre-action listener: _resource_record_pre_action 05:46:40 INFO - Running main action method: create_virtualenv 05:46:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:46:40 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 05:46:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:46:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:40 INFO - Using real prefix '/usr' 05:46:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:46:41 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:43 INFO - Installing pip.................done. 05:46:43 INFO - Return code: 0 05:46:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:46:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:46:43 INFO - Reading from file tmpfile_stdout 05:46:43 INFO - Output received: 05:46:43 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) 05:46:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:46:43 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')]} 05:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:43 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')]} 05:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:43 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:43 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 05:46:43 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 05:46:43 INFO - Using env: {'DISPLAY': ':0', 05:46:43 INFO - 'HOME': '/home/cltbld', 05:46:43 INFO - 'LANG': 'en_US.UTF-8', 05:46:43 INFO - 'LANGUAGE': 'en_US:en', 05:46:43 INFO - 'LOGNAME': 'cltbld', 05:46:43 INFO - 'MAIL': '/var/mail/cltbld', 05:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:43 INFO - 'MOZ_NO_REMOTE': '1', 05:46:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:43 INFO - 'NO_EM_RESTART': '1', 05:46:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:43 INFO - 'PWD': '/builds/slave/test', 05:46:43 INFO - 'SHELL': '/bin/bash', 05:46:43 INFO - 'SHLVL': '1', 05:46:43 INFO - 'TERM': 'linux', 05:46:43 INFO - 'TMOUT': '86400', 05:46:43 INFO - 'USER': 'cltbld', 05:46:43 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:43 INFO - '_': '/tools/buildbot/bin/python'} 05:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:43 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 05:46:43 INFO - Cleaning up... 05:46:43 INFO - Return code: 0 05:46:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:46:43 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')]} 05:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:43 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')]} 05:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:43 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:43 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 05:46:43 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 05:46:43 INFO - Using env: {'DISPLAY': ':0', 05:46:43 INFO - 'HOME': '/home/cltbld', 05:46:43 INFO - 'LANG': 'en_US.UTF-8', 05:46:43 INFO - 'LANGUAGE': 'en_US:en', 05:46:43 INFO - 'LOGNAME': 'cltbld', 05:46:43 INFO - 'MAIL': '/var/mail/cltbld', 05:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:43 INFO - 'MOZ_NO_REMOTE': '1', 05:46:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:43 INFO - 'NO_EM_RESTART': '1', 05:46:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:43 INFO - 'PWD': '/builds/slave/test', 05:46:43 INFO - 'SHELL': '/bin/bash', 05:46:43 INFO - 'SHLVL': '1', 05:46:43 INFO - 'TERM': 'linux', 05:46:43 INFO - 'TMOUT': '86400', 05:46:43 INFO - 'USER': 'cltbld', 05:46:43 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:43 INFO - '_': '/tools/buildbot/bin/python'} 05:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:44 INFO - Downloading/unpacking psutil>=3.1.1 05:46:44 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 05:46:44 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 05:46:44 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 05:46:44 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 05:46:44 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 05:46:44 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 05:46:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:46 INFO - Installing collected packages: psutil 05:46:46 INFO - Running setup.py install for psutil 05:46:46 INFO - building 'psutil._psutil_linux' extension 05:46:46 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 05:46:46 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 05:46:46 INFO - building 'psutil._psutil_posix' extension 05:46:46 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 05:46:46 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 05:46:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:46 INFO - Successfully installed psutil 05:46:46 INFO - Cleaning up... 05:46:46 INFO - Return code: 0 05:46:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:46 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:46 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 05:46:46 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 05:46:46 INFO - Using env: {'DISPLAY': ':0', 05:46:46 INFO - 'HOME': '/home/cltbld', 05:46:46 INFO - 'LANG': 'en_US.UTF-8', 05:46:46 INFO - 'LANGUAGE': 'en_US:en', 05:46:46 INFO - 'LOGNAME': 'cltbld', 05:46:46 INFO - 'MAIL': '/var/mail/cltbld', 05:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:46 INFO - 'MOZ_NO_REMOTE': '1', 05:46:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:46 INFO - 'NO_EM_RESTART': '1', 05:46:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:46 INFO - 'PWD': '/builds/slave/test', 05:46:46 INFO - 'SHELL': '/bin/bash', 05:46:46 INFO - 'SHLVL': '1', 05:46:46 INFO - 'TERM': 'linux', 05:46:46 INFO - 'TMOUT': '86400', 05:46:46 INFO - 'USER': 'cltbld', 05:46:46 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:46 INFO - '_': '/tools/buildbot/bin/python'} 05:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:46:46 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 05:46:46 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 05:46:46 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 05:46:46 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 05:46:46 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 05:46:46 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 05:46:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:46:48 INFO - Installing collected packages: mozsystemmonitor 05:46:48 INFO - Running setup.py install for mozsystemmonitor 05:46:49 INFO - Successfully installed mozsystemmonitor 05:46:49 INFO - Cleaning up... 05:46:49 INFO - Return code: 0 05:46:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:49 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:49 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 05:46:49 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 05:46:49 INFO - Using env: {'DISPLAY': ':0', 05:46:49 INFO - 'HOME': '/home/cltbld', 05:46:49 INFO - 'LANG': 'en_US.UTF-8', 05:46:49 INFO - 'LANGUAGE': 'en_US:en', 05:46:49 INFO - 'LOGNAME': 'cltbld', 05:46:49 INFO - 'MAIL': '/var/mail/cltbld', 05:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:49 INFO - 'MOZ_NO_REMOTE': '1', 05:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:49 INFO - 'NO_EM_RESTART': '1', 05:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:49 INFO - 'PWD': '/builds/slave/test', 05:46:49 INFO - 'SHELL': '/bin/bash', 05:46:49 INFO - 'SHLVL': '1', 05:46:49 INFO - 'TERM': 'linux', 05:46:49 INFO - 'TMOUT': '86400', 05:46:49 INFO - 'USER': 'cltbld', 05:46:49 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:49 INFO - '_': '/tools/buildbot/bin/python'} 05:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:49 INFO - Downloading/unpacking jsonschema==2.5.1 05:46:49 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 05:46:49 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 05:46:49 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 05:46:49 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 05:46:49 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 05:46:49 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 05:46:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:46:51 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 05:46:51 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 05:46:51 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 05:46:51 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 05:46:51 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 05:46:51 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 05:46:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:51 INFO - warning: no files found matching '*.txt' 05:46:51 INFO - no previously-included directories found matching 'build' 05:46:51 INFO - no previously-included directories found matching 'dist' 05:46:51 INFO - no previously-included directories found matching '.git*' 05:46:51 INFO - Installing collected packages: jsonschema, functools32 05:46:51 INFO - Running setup.py install for functools32 05:46:51 INFO - warning: no files found matching '*.txt' 05:46:51 INFO - no previously-included directories found matching 'build' 05:46:51 INFO - no previously-included directories found matching 'dist' 05:46:51 INFO - no previously-included directories found matching '.git*' 05:46:51 INFO - Successfully installed jsonschema functools32 05:46:51 INFO - Cleaning up... 05:46:51 INFO - Return code: 0 05:46:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:46:51 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')]} 05:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:51 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')]} 05:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:51 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:51 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 05:46:51 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 05:46:51 INFO - Using env: {'DISPLAY': ':0', 05:46:51 INFO - 'HOME': '/home/cltbld', 05:46:51 INFO - 'LANG': 'en_US.UTF-8', 05:46:51 INFO - 'LANGUAGE': 'en_US:en', 05:46:51 INFO - 'LOGNAME': 'cltbld', 05:46:51 INFO - 'MAIL': '/var/mail/cltbld', 05:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:51 INFO - 'MOZ_NO_REMOTE': '1', 05:46:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:51 INFO - 'NO_EM_RESTART': '1', 05:46:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:51 INFO - 'PWD': '/builds/slave/test', 05:46:51 INFO - 'SHELL': '/bin/bash', 05:46:51 INFO - 'SHLVL': '1', 05:46:51 INFO - 'TERM': 'linux', 05:46:51 INFO - 'TMOUT': '86400', 05:46:51 INFO - 'USER': 'cltbld', 05:46:51 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:51 INFO - '_': '/tools/buildbot/bin/python'} 05:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:46:52 INFO - Cleaning up... 05:46:52 INFO - Return code: 0 05:46:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:46:52 INFO - Using env: {'DISPLAY': ':0', 05:46:52 INFO - 'HOME': '/home/cltbld', 05:46:52 INFO - 'LANG': 'en_US.UTF-8', 05:46:52 INFO - 'LANGUAGE': 'en_US:en', 05:46:52 INFO - 'LOGNAME': 'cltbld', 05:46:52 INFO - 'MAIL': '/var/mail/cltbld', 05:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:52 INFO - 'MOZ_NO_REMOTE': '1', 05:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:52 INFO - 'NO_EM_RESTART': '1', 05:46:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:52 INFO - 'PWD': '/builds/slave/test', 05:46:52 INFO - 'SHELL': '/bin/bash', 05:46:52 INFO - 'SHLVL': '1', 05:46:52 INFO - 'TERM': 'linux', 05:46:52 INFO - 'TMOUT': '86400', 05:46:52 INFO - 'USER': 'cltbld', 05:46:52 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:52 INFO - '_': '/tools/buildbot/bin/python'} 05:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:46:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:55 INFO - Downloading docopt-0.6.1.tar.gz 05:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:55 INFO - Running setup.py install for blobuploader 05:46:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:46:55 INFO - Running setup.py install for requests 05:46:55 INFO - Running setup.py install for docopt 05:46:55 INFO - Successfully installed blobuploader requests docopt 05:46:55 INFO - Cleaning up... 05:46:55 INFO - Return code: 0 05:46:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:55 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:55 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 05:46:55 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 05:46:55 INFO - Using env: {'DISPLAY': ':0', 05:46:55 INFO - 'HOME': '/home/cltbld', 05:46:55 INFO - 'LANG': 'en_US.UTF-8', 05:46:55 INFO - 'LANGUAGE': 'en_US:en', 05:46:55 INFO - 'LOGNAME': 'cltbld', 05:46:55 INFO - 'MAIL': '/var/mail/cltbld', 05:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:55 INFO - 'MOZ_NO_REMOTE': '1', 05:46:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:55 INFO - 'NO_EM_RESTART': '1', 05:46:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:55 INFO - 'PWD': '/builds/slave/test', 05:46:55 INFO - 'SHELL': '/bin/bash', 05:46:55 INFO - 'SHLVL': '1', 05:46:55 INFO - 'TERM': 'linux', 05:46:55 INFO - 'TMOUT': '86400', 05:46:55 INFO - 'USER': 'cltbld', 05:46:55 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:55 INFO - '_': '/tools/buildbot/bin/python'} 05:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:55 INFO - Running setup.py (path:/tmp/pip-i4T5Ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:56 INFO - Running setup.py (path:/tmp/pip-5fOkB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:56 INFO - Running setup.py (path:/tmp/pip-IWGlIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:56 INFO - Running setup.py (path:/tmp/pip-1HnmGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:56 INFO - Running setup.py (path:/tmp/pip-TewZcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:56 INFO - Running setup.py (path:/tmp/pip-3DCROl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:56 INFO - Running setup.py (path:/tmp/pip-r9Mp8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:56 INFO - Running setup.py (path:/tmp/pip-CC1eNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:56 INFO - Running setup.py (path:/tmp/pip-g9F98S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:56 INFO - Running setup.py (path:/tmp/pip-SVeLjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:56 INFO - Running setup.py (path:/tmp/pip-N3Rya6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:56 INFO - Running setup.py (path:/tmp/pip-xL9SMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:56 INFO - Running setup.py (path:/tmp/pip-rd4FCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:56 INFO - Running setup.py (path:/tmp/pip-lU6h4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:56 INFO - Running setup.py (path:/tmp/pip-F4U0Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:57 INFO - Running setup.py (path:/tmp/pip-i2AlUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:57 INFO - Running setup.py (path:/tmp/pip-pORiUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:57 INFO - Running setup.py install for manifestparser 05:46:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Running setup.py install for mozcrash 05:46:57 INFO - Running setup.py install for mozdebug 05:46:57 INFO - Running setup.py install for mozdevice 05:46:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Running setup.py install for mozfile 05:46:57 INFO - Running setup.py install for mozhttpd 05:46:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Running setup.py install for mozinfo 05:46:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Running setup.py install for mozInstall 05:46:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Running setup.py install for mozleak 05:46:58 INFO - Running setup.py install for mozlog 05:46:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:46:58 INFO - Running setup.py install for moznetwork 05:46:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:46:58 INFO - Running setup.py install for mozprocess 05:46:58 INFO - Running setup.py install for mozprofile 05:46:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:46:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:46:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:46:58 INFO - Running setup.py install for mozrunner 05:46:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:46:58 INFO - Running setup.py install for mozscreenshot 05:46:58 INFO - Running setup.py install for moztest 05:46:58 INFO - Running setup.py install for mozversion 05:46:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:46:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:58 INFO - Cleaning up... 05:46:58 INFO - Return code: 0 05:46:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:58 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:46:58 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 05:46:58 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 05:46:58 INFO - Using env: {'DISPLAY': ':0', 05:46:58 INFO - 'HOME': '/home/cltbld', 05:46:58 INFO - 'LANG': 'en_US.UTF-8', 05:46:58 INFO - 'LANGUAGE': 'en_US:en', 05:46:58 INFO - 'LOGNAME': 'cltbld', 05:46:58 INFO - 'MAIL': '/var/mail/cltbld', 05:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:58 INFO - 'MOZ_NO_REMOTE': '1', 05:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:58 INFO - 'NO_EM_RESTART': '1', 05:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:58 INFO - 'PWD': '/builds/slave/test', 05:46:58 INFO - 'SHELL': '/bin/bash', 05:46:58 INFO - 'SHLVL': '1', 05:46:58 INFO - 'TERM': 'linux', 05:46:58 INFO - 'TMOUT': '86400', 05:46:58 INFO - 'USER': 'cltbld', 05:46:58 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:58 INFO - '_': '/tools/buildbot/bin/python'} 05:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:59 INFO - Running setup.py (path:/tmp/pip-Ja5vRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:59 INFO - Running setup.py (path:/tmp/pip-KwIzrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:59 INFO - Running setup.py (path:/tmp/pip-qD2xKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:59 INFO - Running setup.py (path:/tmp/pip-0VeOXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:59 INFO - Running setup.py (path:/tmp/pip-uL_lA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:59 INFO - Running setup.py (path:/tmp/pip-YDKqMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:59 INFO - Running setup.py (path:/tmp/pip-rTvsAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:59 INFO - Running setup.py (path:/tmp/pip-WHFC9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:59 INFO - Running setup.py (path:/tmp/pip-jV_3Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:59 INFO - Running setup.py (path:/tmp/pip-P99823-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:59 INFO - Running setup.py (path:/tmp/pip-9MgaDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:59 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)) 05:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:59 INFO - Running setup.py (path:/tmp/pip-sZeIjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 05:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:00 INFO - Running setup.py (path:/tmp/pip-JdwlRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:00 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)) 05:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:00 INFO - Running setup.py (path:/tmp/pip-7dR3dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 05:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:00 INFO - Running setup.py (path:/tmp/pip-ajorlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:00 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)) 05:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:00 INFO - Running setup.py (path:/tmp/pip-qa2NDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 05:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:00 INFO - Running setup.py (path:/tmp/pip-w28TlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:00 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)) 05:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47:00 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)) 05:47:00 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:00 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:02 INFO - Downloading blessings-1.6.tar.gz 05:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:47:02 INFO - Installing collected packages: blessings 05:47:02 INFO - Running setup.py install for blessings 05:47:02 INFO - Successfully installed blessings 05:47:02 INFO - Cleaning up... 05:47:02 INFO - Return code: 0 05:47:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:47:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:03 INFO - Reading from file tmpfile_stdout 05:47:03 INFO - Current package versions: 05:47:03 INFO - argparse == 1.2.1 05:47:03 INFO - blessings == 1.6 05:47:03 INFO - blobuploader == 1.2.4 05:47:03 INFO - docopt == 0.6.1 05:47:03 INFO - functools32 == 3.2.3-2 05:47:03 INFO - jsonschema == 2.5.1 05:47:03 INFO - manifestparser == 1.1 05:47:03 INFO - mozInstall == 1.12 05:47:03 INFO - mozcrash == 1.0 05:47:03 INFO - mozdebug == 0.1 05:47:03 INFO - mozdevice == 0.49 05:47:03 INFO - mozfile == 1.2 05:47:03 INFO - mozhttpd == 0.7 05:47:03 INFO - mozinfo == 0.9 05:47:03 INFO - mozleak == 0.1 05:47:03 INFO - mozlog == 3.4 05:47:03 INFO - moznetwork == 0.27 05:47:03 INFO - mozprocess == 0.25 05:47:03 INFO - mozprofile == 0.28 05:47:03 INFO - mozrunner == 6.13 05:47:03 INFO - mozscreenshot == 0.1 05:47:03 INFO - mozsystemmonitor == 0.3 05:47:03 INFO - moztest == 0.8 05:47:03 INFO - mozversion == 1.4 05:47:03 INFO - psutil == 3.1.1 05:47:03 INFO - requests == 1.2.3 05:47:03 INFO - wsgiref == 0.1.2 05:47:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:03 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:47:03 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 05:47:03 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 05:47:03 INFO - Using env: {'DISPLAY': ':0', 05:47:03 INFO - 'HOME': '/home/cltbld', 05:47:03 INFO - 'LANG': 'en_US.UTF-8', 05:47:03 INFO - 'LANGUAGE': 'en_US:en', 05:47:03 INFO - 'LOGNAME': 'cltbld', 05:47:03 INFO - 'MAIL': '/var/mail/cltbld', 05:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:03 INFO - 'MOZ_NO_REMOTE': '1', 05:47:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:47:03 INFO - 'NO_EM_RESTART': '1', 05:47:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:03 INFO - 'PWD': '/builds/slave/test', 05:47:03 INFO - 'SHELL': '/bin/bash', 05:47:03 INFO - 'SHLVL': '1', 05:47:03 INFO - 'TERM': 'linux', 05:47:03 INFO - 'TMOUT': '86400', 05:47:03 INFO - 'USER': 'cltbld', 05:47:03 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:03 INFO - '_': '/tools/buildbot/bin/python'} 05:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 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)) 05:47:03 INFO - Cleaning up... 05:47:03 INFO - Return code: 0 05:47:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:03 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 0xee31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1180b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', '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 05:47:03 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 05:47:03 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 05:47:03 INFO - Using env: {'DISPLAY': ':0', 05:47:03 INFO - 'HOME': '/home/cltbld', 05:47:03 INFO - 'LANG': 'en_US.UTF-8', 05:47:03 INFO - 'LANGUAGE': 'en_US:en', 05:47:03 INFO - 'LOGNAME': 'cltbld', 05:47:03 INFO - 'MAIL': '/var/mail/cltbld', 05:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:03 INFO - 'MOZ_NO_REMOTE': '1', 05:47:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:47:03 INFO - 'NO_EM_RESTART': '1', 05:47:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:03 INFO - 'PWD': '/builds/slave/test', 05:47:03 INFO - 'SHELL': '/bin/bash', 05:47:03 INFO - 'SHLVL': '1', 05:47:03 INFO - 'TERM': 'linux', 05:47:03 INFO - 'TMOUT': '86400', 05:47:03 INFO - 'USER': 'cltbld', 05:47:03 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:03 INFO - '_': '/tools/buildbot/bin/python'} 05:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:47:03 INFO - Cleaning up... 05:47:03 INFO - Return code: 0 05:47:03 INFO - Running post-action listener: _resource_record_post_action 05:47:03 INFO - Running post-action listener: _start_resource_monitoring 05:47:03 INFO - Starting resource monitoring. 05:47:03 INFO - [mozharness: 2017-02-20 13:47:03.522424Z] Finished create-virtualenv step (success) 05:47:03 INFO - [mozharness: 2017-02-20 13:47:03.522951Z] Running install step. 05:47:03 INFO - Running pre-action listener: _resource_record_pre_action 05:47:03 INFO - Running main action method: install 05:47:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:03 INFO - Reading from file tmpfile_stdout 05:47:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:47:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:47:03 INFO - Reading from file tmpfile_stdout 05:47:03 INFO - Output received: 05:47:03 INFO - Usage: mozinstall [options] installer 05:47:03 INFO - Options: 05:47:03 INFO - -h, --help show this help message and exit 05:47:03 INFO - -d DEST, --destination=DEST 05:47:03 INFO - Directory to install application into. [default: 05:47:03 INFO - "/builds/slave/test"] 05:47:03 INFO - --app=APP Application being installed. [default: firefox] 05:47:03 INFO - mkdir: /builds/slave/test/build/application 05:47:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:47:19 INFO - Reading from file tmpfile_stdout 05:47:19 INFO - Output received: 05:47:19 INFO - /builds/slave/test/build/application/firefox/firefox 05:47:19 INFO - Running post-action listener: _resource_record_post_action 05:47:19 INFO - [mozharness: 2017-02-20 13:47:19.195624Z] Finished install step (success) 05:47:19 INFO - [mozharness: 2017-02-20 13:47:19.195891Z] Running run-tests step. 05:47:19 INFO - Running pre-action listener: _resource_record_pre_action 05:47:19 INFO - Running main action method: run_tests 05:47:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:47:19 INFO - Python 2.7.3 05:47:19 INFO - Return code: 0 05:47:19 INFO - grabbing minidump binary from tooltool 05:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:19 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 0x117e290>, '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 0x117eb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x117f040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:47:19 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 05:47:19 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 05:47:19 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 05:47:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:47:19 INFO - Return code: 0 05:47:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:47:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:47:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:47:19 INFO - ENV: RUST_BACKTRACE is now 1 05:47:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:47:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:47:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:47:19 INFO - Using env: {'DISPLAY': ':0', 05:47:19 INFO - 'HOME': '/home/cltbld', 05:47:19 INFO - 'LANG': 'en_US.UTF-8', 05:47:19 INFO - 'LANGUAGE': 'en_US:en', 05:47:19 INFO - 'LOGNAME': 'cltbld', 05:47:19 INFO - 'MAIL': '/var/mail/cltbld', 05:47:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:47:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:19 INFO - 'MOZ_NO_REMOTE': '1', 05:47:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:47:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:47:19 INFO - 'NO_EM_RESTART': '1', 05:47:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:19 INFO - 'PWD': '/builds/slave/test', 05:47:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:47:19 INFO - 'RUST_BACKTRACE': '1', 05:47:19 INFO - 'SHELL': '/bin/bash', 05:47:19 INFO - 'SHLVL': '1', 05:47:19 INFO - 'TERM': 'linux', 05:47:19 INFO - 'TMOUT': '86400', 05:47:19 INFO - 'USER': 'cltbld', 05:47:19 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490', 05:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:19 INFO - '_': '/tools/buildbot/bin/python'} 05:47:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:47:19 INFO - mozversion application_buildid: 20170220130544 05:47:19 INFO - mozversion application_changeset: 24931c1b6d9e5c0b1e496a2428f2012428715956 05:47:19 INFO - mozversion application_display_name: Nightly 05:47:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:47:19 INFO - mozversion application_name: Firefox 05:47:19 INFO - mozversion application_remotingname: firefox 05:47:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:47:19 INFO - mozversion application_vendor: Mozilla 05:47:19 INFO - mozversion application_version: 54.0a1 05:47:19 INFO - mozversion platform_buildid: 20170220130544 05:47:19 INFO - mozversion platform_changeset: 24931c1b6d9e5c0b1e496a2428f2012428715956 05:47:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:47:19 INFO - mozversion platform_version: 54.0a1 05:47:19 INFO - using testdate: 1487598439 05:47:19 INFO - actual date: 1487598439 05:47:19 INFO - starting webserver on 'localhost:47336' 05:47:19 INFO - SUITE-START | Running 2 tests 05:47:19 INFO - TEST-START | dromaeo_css 05:47:19 INFO - operating with platform_type : linux_ 05:47:19 INFO - Initialising browser for dromaeo_css test... 05:47:19 INFO - TEST-INFO | started process 31669 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDzdV0T/profile http://localhost:47336/getInfo.html) 05:47:22 INFO - PROCESS | 31669 | Iterating over profile /tmp/tmpDzdV0T/profile 05:47:23 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:23 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/crashes = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:23 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:23 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/extensions.ini = Mon Feb 20 2017 05:47:20 GMT-0800 (PST) 05:47:23 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:23 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/prefs.js = Mon Feb 20 2017 05:46:39 GMT-0800 (PST) 05:47:23 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:23 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/pluginreg.dat = Mon Feb 20 2017 05:47:21 GMT-0800 (PST) 05:47:23 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:23 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/extensions = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:23 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:23 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/content-prefs.sqlite = Mon Feb 20 2017 05:47:21 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/frequencyCap.json = Mon Feb 20 2017 05:47:22 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/places.sqlite-wal = Mon Feb 20 2017 05:47:24 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/sessionCheckpoints.json = Mon Feb 20 2017 05:47:23 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/permissions.sqlite = Mon Feb 20 2017 05:47:20 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/extensions.json = Mon Feb 20 2017 05:47:22 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/secmod.db = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/startupCache = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | __metrics Screen width/height:1600/1200 05:47:24 INFO - PROCESS | 31669 | colorDepth:24 05:47:24 INFO - PROCESS | 31669 | Browser inner width/height: 1024/697 05:47:24 INFO - PROCESS | 31669 | __metrics 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/safebrowsing = Mon Feb 20 2017 05:47:21 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/addons.json = Mon Feb 20 2017 05:47:22 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | Stat failure 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/blocklist.xml = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/key3.db = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/thumbnails = Mon Feb 20 2017 05:47:21 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/user.js = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/minidumps = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/places.sqlite = Mon Feb 20 2017 05:47:23 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/places.sqlite-shm = Mon Feb 20 2017 05:47:21 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/directoryLinks.json = Mon Feb 20 2017 05:47:22 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/cache2 = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/compatibility.ini = Mon Feb 20 2017 05:47:20 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/cert8.db = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/localstore.rdf = Mon Feb 20 2017 05:46:39 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/gmp = Mon Feb 20 2017 05:47:20 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/bookmarkbackups = Mon Feb 20 2017 05:47:21 GMT-0800 (PST) 05:47:24 INFO - PROCESS | 31669 | No birth date. Using mtime. 05:47:24 INFO - PROCESS | 31669 | Using date: /tmp/tmpDzdV0T/profile/.parentlock = Mon Feb 20 2017 05:47:19 GMT-0800 (PST) 05:47:24 ERROR - PROCESS | 31669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 05:47:25 INFO - TEST-INFO | 31669: exit 0 05:47:25 INFO - Browser initialized. 05:47:25 INFO - Running cycle 1/1 for dromaeo_css test... 05:47:25 INFO - TEST-INFO | started process 31795 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDzdV0T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:37 INFO - PROCESS | 31795 | RSS: Main: 153493504 05:47:37 INFO - PROCESS | 31795 | 05:50:42 INFO - PROCESS | 31795 | Cycle 1(1): loaded http://localhost:47336/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47336/tests/dromaeo/cssquery-ext.html) 05:50:44 INFO - PROCESS | 31795 | RSS: Main: 163438592 05:50:44 INFO - PROCESS | 31795 | 05:52:26 INFO - PROCESS | 31795 | ************************* 05:52:26 INFO - PROCESS | 31795 | A coding exception was thrown and uncaught in a Task. 05:52:26 INFO - PROCESS | 31795 | 05:52:26 ERROR - PROCESS | 31795 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:52:26 INFO - PROCESS | 31795 | Full stack: 05:52:26 INFO - PROCESS | 31795 | ************************* 05:53:46 INFO - PROCESS | 31795 | Cycle 1(1): loaded http://localhost:47336/tests/dromaeo/cssquery-ext.html (next: http://localhost:47336/tests/dromaeo/cssquery-jquery.html) 05:53:47 INFO - PROCESS | 31795 | RSS: Main: 158814208 05:53:47 INFO - PROCESS | 31795 | 05:56:49 INFO - PROCESS | 31795 | Cycle 1(1): loaded http://localhost:47336/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47336/tests/dromaeo/cssquery-mootools.html) 05:56:49 INFO - PROCESS | 31795 | RSS: Main: 166379520 05:56:49 INFO - PROCESS | 31795 | 05:59:51 INFO - PROCESS | 31795 | Cycle 1(1): loaded http://localhost:47336/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47336/tests/dromaeo/cssquery-prototype.html) 05:59:52 INFO - PROCESS | 31795 | RSS: Main: 159166464 05:59:52 INFO - PROCESS | 31795 | 06:02:55 INFO - PROCESS | 31795 | Cycle 1(1): loaded http://localhost:47336/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47336/tests/dromaeo/cssquery-yui.html) 06:02:56 INFO - PROCESS | 31795 | RSS: Main: 165351424 06:02:56 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | Cycle 1(1): loaded http://localhost:47336/tests/dromaeo/cssquery-yui.html (next: http://localhost:47336/tests/dromaeo/cssquery-dojo.html) 06:05:59 INFO - PROCESS | 31795 | RSS: Main: 159354880 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | __start_tp_report 06:05:59 INFO - PROCESS | 31795 | _x_x_mozilla_page_load 06:05:59 INFO - PROCESS | 31795 | _x_x_mozilla_page_load_details 06:05:59 INFO - PROCESS | 31795 | |i|pagename|runs| 06:05:59 INFO - PROCESS | 31795 | |0;dojo.html;2557.7825884799795;2585.560454722697;2590.5855063189892;2623.5671114266147;2632.039305653436;4777.546133117357;4830.323754674339;4831.420229572447;4843.225083986563;4868.951310486894;4814.614830813535;4836.854894353175;4851.781669824857;4877.585405240558;4890.510948905107;4247.341662042373;4485.573870482299;4574.130915126116;4596.839110631128;4614.215583350839;4566.041131362418;4574.542545745416;4584.19776539106;4591.908161836761;4597.126545956257;4244.851430199959;4284.036091879333;4394.362817391492;4473.9105217895785;4484.304932735431;4412.029353542215;4429.402030725843;4442.133783912139;4487.057717879249;4497.527759585229;4376.540463251376;4402.471703395581;4411.580899814512;4424.646028317727;4445.088756804847;4665.300204969248;4690.64820138491;4769.117713223065;4797.448293446263;4803.279508073782;5214.191800270964;5226.764795584208;5257.316548848664;5258.6581872178185;5265.76304066318;5228.398734145546;5240.135377662681;5250.9474905250845;5262.394824595182;5311.920321195185;4567.725936443824;4881.194602890554;4892.094962431962;4898.877528061791;4899.975500122477;5020.7740651670765;5072.137736584789;5188.299579556711;5247.422783493813;5275.208718692227;4902.48523904992;5048.798048078109;5083.237514372874;5095.108356037678;5103.157978933508;5173.611979101583;5186.559142472932;5188.066148093304;5189.42916279209;5191.117510023229;4866.683665561727;5080.161773307437;5123.666961647481;5165.870853228699;5174.948250517522;4876.951455437098;4931.939632978927;4953.950460495421;4972.950270497248;4983.426905905867;4227.957720422818;4236.830526778895;4249.830006799755;4252.192083504125;4355.60799528044;3772.2078363543965;3825.445310429972;3831.080540670275;3846.0961674006585;3861.343571592836;4493.865184044484;4495;4498.6626003049905;4506.616937560344;4522.4573051234065;4924.75376231187;4972.701637901738;5214.426413094557;5240.135377662643;5246.160614301693;4472.8210871565425;4509.098180363875;4512.2329204035395;4515.661325400608;4567.429071366079;4668.4631267404;4716.95283047172;4723.7638118093955;4725.740084295397;4765.046990601824;4605.815767369268;4876.463589005207;4922.556969872586;4929.112759703145;4971.701697898137;4944.92582611254;5143.8714032148;5143.948560514347;5170.5346518813485;5230.189320655152;4805.567498924974;4806.182948898766;4823.855284341475;4839.959408727141;4857.247126695368;5064.569511591409;5081.0854046272025;5090.134677104827;5090.796368145381;5143.254228136974;4931.9753401232765;4986.675866068694;5195.3246078009615;5239.161734122522;5245.868853278698;5108.719020453762;5117.565006974449;5126.948730512647;5127.128388173945;5165.896682066413;4892.461829198856;4907.607391408607;5086.160783470834;5123.795048198031;5150.665206761699;4890.951090489049;4949.752512374295;4950.306957026091;4952.8761780955765;4953.777080031479;5140.691558506502;5149.716765577929;5156.329677141947;5199.55803756685;5209.869753256199;4984.277279794482;5016.623753218596;5020.196768517019;5040.470750571138;5045.066662667418 06:05:59 INFO - PROCESS | 31795 | |1;ext.html;541.8699512117092;548.4159370270661;555.1783360626273;589.5254320272179;601.7833579911231;33973;37774;37843;37862.621373786256;37914.8104259479;38223.23553528928;39068.609313906854;39091.41362879559;39105;39170;7291.416686665068;7525.435592330569;9255.907440925606;9719.708408747749;9724.465154416504;9370.39092458988;9401.717948461523;9431.95284023579;9441.339106262565;9454;9437.622495100188;9531.95234023829;9536.380135291218;9543.952280238624;9544.90455095447;9412.717618471457;9425.622975080989;9478.099580539838;9493.525323733855;9503.809923801555;9409.200217981479;9432.669856554987;9467.100625440573;9490.050994900525;9503.334766566342;33129.83435082809;33239.50140747891;33311;33417.16457088592;33465.83267083673;3225.645179030305;3338.6327503974785;3398.8130652814084;3414.590249170091;3455.101673564866;22332.888335558382;22562.774372256397;22661.546769064527;22680.65979010316;22705.20531781396;3163.636181839072;3287.474004159347;3309.12308238317;3434.553508043938;3438.6905178534053;218597;219812;220805;221106;222110;39323.213535729126;40159.3976090353;45936.770316148206;47274;47307.526924730315;50220.24669629958;50391.49608503941;50463.24305135426;50563;50857;12883.098183127091;13018.609441716764;13058.151220170848;13062.281574513492;13067.150635208682;9290.674826381179;9393.389429687048;9402.200812930841;9413;9570.856437153448;15680.529584112495;15793.157231698404;15835.208239587975;15885.205739712968;15944;6538.542302038907;6686.3952651363325;6714.832129196711;6743.718693448229;6775.491838112062;750.6622020090981;751.2449987762263;770.9746037769753;778.9727359542388;779.8479296537121;25504.744952550238;27032.32419189536;27142.86428567884;27176;27515.86242068777;893.5978809535735;924.4360939826701;928.832810094176;932.2309094996655;947.4930911962067;14314;14591.78112328316;14630.926845365704;14959.925200374146;14970;299091;303931;304102;305287;305700;49491;49545.75227123914;49868;50180.24729629058;50302.74848625807;1106.1703722208345;1139.3050239354154;1164.0338519029185;1171.6250799744;1177.252444697623;24857.378565536008;25553.744462555136;26553.423781006204;26606.467870642868;26838.852891769002;41417.58582414137;41762;41762.164756704085;41854.58145418507;41895.37156942587;254345;255696.93666848502;256398;256411;257207;22722.659160112282;23801;23811.64282535798;24635.876820615784;24918.626220607068;33271.16822079467;33419.16452088717;33465;33816.49275260824;34397.484037738956;27235.86382068077;28730.712692872803;29405.944552159268;29416;30706;28525.857370713013;29062.41875162527;30097.3980520384;30130.24674383158;30161.396772064876;29825.850870746377;29970.850145749133;30106.580815453428;30187.547186791777;30300.393992120476;25782.613260801478;26727.732722672525;27226.45547089087;27242;27297.79411764728;20734.377968660963;20937.790622093584;21115.47211319668;21213.787862121182;21320.14719411206 06:05:59 INFO - PROCESS | 31795 | |2;jquery.html;380.0802059017178;383.91170030892914;403.4210907347956;406.64215490368474;418.96857735669835;16109.677806443879;17510.124493775325;17656.38202662907;17726.290948362068;18415.26338946444;16831.495055148363;17686.911565442188;18119.275228990857;18173.7273940891;18229;5149.716765577892;5155.71643559604;5299.814506492274;5359.705216213107;5363.195520671911;5155.922661160085;5281.6830990140525;5286.074936886049;5286.550643195333;5288.92066619001;5196.324477817879;5209.011201907546;5227;5237.685738855662;5248.947510524884;5122.487751224867;5183.714895680736;5201.55786758126;5217.243499692523;5230.607704422184;4633.837815676435;4659.627229821606;4682.391289132425;4688.367070445514;4690.882727931795;20808.79191208099;20858.687119693215;21044.5791084179;21063.157473701034;21090;2163.2644900733826;2199.714037175173;2225.888705564715;2244.955100897973;2269.3305474884946;13268.3365831709;13428.19430834153;13487.662808429868;13628.250446225453;14049.578512644637;1607.26869274481;1650.801903771543;1670.5656529302348;1696.4062578097692;1737.1140718233792;72806.99123096456;72839.63580182118;74098.25901740967;74186.25813741848;75531.24468755352;9587.952060239653;9761.414315141114;9858.162056225157;10197.9490102549;10484.056434920742;14312.713745725026;14336.21150836714;14358.138511689123;14394.496192633207;14446.783298250535;6782.220044694924;6961.095057642576;7064.646767661699;7194.208637050025;7199.820004499929;6404.775832845828;6435.74257029723;6638.933610663831;6649.567778094505;6671.566348187353;6494.740210391531;6495.415412612888;6521.934780652227;6563.6389998549585;6736.5284430090405;1736.3401907275406;1736.8957862528036;1757.4551888914477;1852.2220667319757;1854.4158590044092;800.803803068252;820.0528389709895;821.5481485183126;822.0669540072062;826.4917075998218;16792.888374806334;16940.491785246464;17184.31262749476;19581.90209048974;19756;957.5690939077443;958.8945216026232;958.9041095890494;959.3668179001826;965.8937516873139;9524.761880953032;9714.174295184988;9976.251781116445;10018.949905250573;10125.797484050425;407167;465635;468810.7748686407;476858;477644;91091;91346.54326728456;91404;94211;94735.52632236933;988.3674448353204;1016.1870503597092;1040.7346126737634;1042.489180301662;1056.6618682021833;15156.696866062397;15362.155081470626;15695.607609809846;15825.208739562746;16047.277872495531;39256.80371598124;39526.80236598913;39714.40428393519;40405.595944040186;40672;49625.50374496209;49901.25148122854;51314.74342628263;57605.423945761686;57750.4224957745;5364.249005139206;6503.154589903283;6640.335966403321;7630.694772209049;7948.211652378169;7210.1708303545765;9809.920908699894;11035.558577656804;11786.116041296766;11802;9071.50106744109;9551.90448095538;9968;10071.143952764098;10389.013043760831;10145.391276523433;10506.317089389166;10633;10716.08913242352;11024.944875275893;5636.85907852307;9589.184919281939;9640.132388084825;9924.454155021544;11223.66329010131;7599.354054905395;7741.5742134183165;7911.248431399008;7993.960030199812;8183.508989460652;9288.34981551312;10404.427756473468;10672;10794.244402891722;10824.56701731922 06:05:59 INFO - PROCESS | 31795 | |3;mootools.html;1347.366737633312;1352.4725357110726;1354.3972932045244;1357.0975301424553;1365.6244532753499;7425.1461081975585;7667.348275396584;7828.608569571513;7911.920880791183;7919.881201781971;7399.889001664979;7718.266764657348;7722.189170137125;7739.187385324544;7746.4190185736015;2142.1645558232303;2185.2024011235944;2185.912563497458;2189.058704756954;2192.725909261342;2119.947001324964;2172.73927128744;2173.7500187478404;2175.869447833127;2191.397365724423;2121.0349291072603;2143.7534683511362;2160.5138843760105;2173.4566358410398;2180.411288951982;2139.1336508713994;2143.6998820165113;2151.3008272311563;2155.3964889830795;2157.8165855902266;1774.4055741326672;1828.6616975859508;2042.714020037196;2098.3075585056895;2150.473134082144;7610.543367397974;7852.803679907991;7864.921350786476;7882.487638303492;7915.920840791576;4459.933101003455;4658.114958157939;4661.930071048937;4665.346851440801;4676.953230467686;2958.778091643135;2970.376220993572;2995.775316851223;3033.2568520712566;3041.741451976585;1124.6569796212152;1154.6189757380043;1154.9133814963911;1159.605734050427;1196.9581064662782;10400.115990140846;10501.31741436804;10571.101456376216;10606.681799546026;10628.840567391419;4064.7561146331313;4123.876283711494;4217.198732240926;4234.513031001413;4357;9104.635814567344;9118;9143.817123657489;9155.313351498664;9168.770780730401;4673.906521869543;4706;4719.174144524694;4720.527947205252;4732.455767586703;4120.711550191518;4141.979290103591;4147.854825081107;4240.067185219247;4241.448611680524;3667.6149004354693;3698.9630103699155;3721.2371463849845;3809.3904975203836;3822.9235415291537;1252.3425201769144;1265.202922159034;1265.6519457149172;1284.1011292095384;1292.515306759965;744.6388501576731;775.6780935911646;782.5462469973435;784.1648644194029;789.9881501777478;4704.388429504143;4849.781759820817;4870.853874383774;4897.975510122498;4960.23116416956;521.9686818790884;523.6517715719081;527.3882296536076;529.9178627312773;531.44994930247;3003.4593773121046;3015.9698403016127;3069.63164420271;3082.1832214463184;3125.6092988376454;8777.780555486164;8925.910740892637;9019.864702029476;9044.547772611342;9371.390859594105;9270.53647317645;9286.62853485853;9308.162265396148;9318.347715659838;9353.924298705735;7175.390091842148;7327.560346379234;7341;7353.485256032026;7354.485186037033;4770.6183505318795;4942.728149951788;4966.801327946841;4992.475790041994;5029.06962211992;8321.16788321163;8477.830443391222;8670.913290867264;8715.302775777796;8791.5164665944;8639.524826134622;8756.518391488275;8758.430702004349;8775.517346545746;8799.604017819085;6503.837404064936;6619.304972977768;6631.701573429111;6638.535302528777;6646.734130634721;7274.30894065063;7283.708651654087;7302.6713797878165;7368.042154519878;7391.482596218213;6717.1267735194115;6733.293004234485;6740;6744.62904540255;6776.627285499345;6579.835504112435;6600.504962127956;6607.867842643216;6629.668516574248;6639.634820084942;5585.664860108406;5692.288463942007;5696.829095127153;5699.487046165699;5706.343770466449;4148.585141485948;4164.167166566638;4186.748795072305;4192.224438478891;4283.443152390294;4204.936925946052;4243.130158317475;4251.256030194641;4349.130173965156;4407.118576284692 06:05:59 INFO - PROCESS | 31795 | |4;prototype.html;347.5707501235973;362.5594902193834;365.0617911966247;366.4594722783894;371.58011447064814;4847.515248475151;4972.428170760364;5067.290579318898;5140.383154021518;5142.460041695619;4936.901261974758;5022.422421421537;5030.446650868402;5032;5064.366954130734;4092.3247664135383;4100.815463304143;4113.095119073799;4122.56713045129;4130.401091841696;3720.9069773255615;4105.137921036571;4111.753294802306;4113.526944401402;4117.855875044374;3859.4017927221184;3865.555461121965;3867.245887052026;3886.9222615547674;3921.8822635548872;3874.786886721229;3879.631435013669;3880.8835734927998;3901.1612503311926;3923.4507168996365;3413.6245013048547;3432.48512723091;3437.4500079987333;3439.398105331582;3447.3450044491465;4652.674312798105;4885.560299573055;4930.654854160211;4938.58022068121;4956.603471722254;4421.137878113769;4433.2685106957315;4433.6674749393605;4440.53374395687;4453;5260.289860868772;5273.182656688219;5276.736163191864;5277.70972596507;5318.707471089088;3525.770824896399;3706.2031663192543;3736.6637002669618;3748.3881930769758;3758.267137908109;5201.45384734605;5348.930213957224;5386.845261895234;5463.235147079412;5496.285482887239;3799.2211596622624;3800.6199380062326;3809.3333666608232;3813.103920578662;3846.8461261549223;3850.7497012694644;3887.6695480883877;3895.4935858338235;3898.2983063048355;3913.3934240192816;4274.700770946066;4401.889952751143;4410.117976404667;4429.379886815848;4444.933326000112;5238.240455133984;5245.370555533361;5261.526462618306;5270.92093618596;5272.261883336336;1279.7120647854158;1292.2827830553945;1292.5394304435927;1296.2093123194843;1300.2653500772033;815.9347252219927;865.9696910608099;872.2847265242567;884.1114679746942;884.1158841158841;4433.7118087324225;4444.711093778894;4450.9985253317745;4457.442819647544;4463.308187230984;5237.659552129099;5254.527092561688;5260.368755749258;5262.447443018505;5283.894322113536;4185.476815398075;4419.513853476115;4422.226110430662;4436.290193569013;4455.242608756519;2742.204760619438;2749.9037533686223;2750.2849259192353;2763.9723602763715;2794.3712664650698;4836.250381190921;4874;4902.823322402669;4914.238293064581;4932.556069953722;5083.517065878736;5256.973715131477;5338.759755810997;5346.385165705994;5403.891922161613;4837.443693975168;4860.343853579792;4867.561919427198;4872.902541949141;4905.313256144214;5131.948680513147;5235.188545775411;5235.973820131027;5248.60635452335;5269;4934.358533390637;5119.795208191779;5154.922676159786;5190.195519694454;5213.556847667991;4784.928226076542;4858.854234372974;4884.389451318585;4937.8271760488215;5011.473795251592;3756.722714277159;3782.9432558512194;3789.677877380343;3797.2974999625158;3797.525309336333;5395.406505284337;5414.864628384322;5445.346558413015;5452.154915988028;5462.617616766947;5102.856800144023;5165.431802501646;5175.16281719999;5188;5218.765155567932;5198.662086964335;5202.97398513005;5209.218617207449;5210.739463026757;5218.869528261824;5137.383513978197;5138.458462461172;5142.562882155059;5161.354830646169;5196.506331898464;4976.353074100489;5289.636050258629;5306.8407947761625;5311.973440132774;5333.066713325075;5145.202493613346;5178.249153872592;5182.870428239174;5203.869903252449;5209.843704688866 06:05:59 INFO - PROCESS | 31795 | |5;yui.html;650.9023646453031;652.1066139389031;704.8942658601209;715.8890371992342;738.4904415952993;11780.941095294522;13104;13115;13130.737385252301;13143.6714082148;12533.749325013494;12650.114491985565;12686.4925402984;12699.936500317508;12774.808377874338;799.2447137455092;814.3770015937812;814.4339683919649;815.1400272712316;851.5571902610639;834.4159088637947;842.5366048673236;843.409613270713;843.4939036578065;845.953472559009;866.3112825303898;867.8524650809346;871.6426265231256;872.5593575244493;874.0254616103031;869.6347534035721;870.7474832298626;871.5032431514039;871.7428358634218;874.97812554686;635.2345423764353;640.4332166033095;641.4964253061371;641.708022849607;642.601587016053;12629.621111366674;12740.490380384774;12750.426230819636;12787.808182877265;13550.119242249222;9812.90187098127;9835.508224588742;9896.356736812084;10377.014183652542;11199.272047316897;11814;11839.13555835185;11853.94073029638;11884.5432962639;11938.22401543897;4058.492688413948;4087.816048277835;4102.917941641151;4147.958520414787;4169.874903752892;56035.15947260794;57738;58317.4168258312;59194;59686.40313596852;9730.75673108178;9752.902470975341;9803.803923921481;9884.55519501624;9960.551775170135;17166.742498862528;17200.569985750455;17313.30746770115;17380.957142571486;17549.824501755076;5834.67909264986;5982.49148822355;6229.345918678564;6258.499320054385;6462.612243265419;3200;3297.0768184907784;3312.8981368692425;3328.5506456628527;3410.6248312685584;2776.3059235191204;2877.525208340642;3250.8049517029053;3385.4752513360468;3397.5073614325784;524.5043433954876;527.343457395545;528.1417696243604;536.9892602147935;540.0522083743084;547.7781498493117;555.1395337227282;564.5455408396199;573.8135106090538;578.9044807606698;7912.564808935449;7922.643481043367;8472.406931514735;8508.659653613786;8715.782105447288;627.2567008095422;631.2078341681253;636.7707625254905;640.2828831708423;643.3630705601514;3955.6835453164254;3998.9200215995515;4032.274190645653;4128.752274863517;4185.832566697298;60844;62528;63603.91168706492;64535.67732161309;65085.67457162684;54780.330753694514;55011;55084.72457637686;55093.72453137708;55433.44566554283;51483;52092.73953630208;52233.216501752504;52984;53031.469685302654;7617.923820761943;7928.524288542706;8050.637721302673;8056.355491560778;8159.061707903667;36135.27729445449;36320.45519317265;37105;37187.628123718416;37931.81034094812;40448.59551404566;43900.56099438965;44352;45174.32238516491;45286.32070518879;10110.342827716175;10142;10295.279330446796;10324.48377581103;10941.12471002302;11135.164862635173;11177.832332515181;11289.943550282196;11333.886661133283;11498.82751758741;9815.901840981498;9882.308238423242;10136.18910487174;10393.896061039293;10577.576896924036;9123.361364704408;9586.808263834824;9730.80538389214;9778.75443240251;9781.070798274153;10091.495425228562;10108.646197383056;10265.692029239135;10353.223508237063;10672.413017284123;8536.573171341284;8772.254358379609;8979.685711000084;9077.77305567366;9129.224015958718;8057.556834374166;8335.249827515552;8687.218150366498;8692.391532592657;8916.465012099296 06:05:59 INFO - PROCESS | 31795 | __end_tp_report 06:05:59 INFO - PROCESS | 31795 | __start_cc_report 06:05:59 INFO - PROCESS | 31795 | _x_x_mozilla_cycle_collect,390 06:05:59 INFO - PROCESS | 31795 | __end_cc_report 06:05:59 INFO - PROCESS | 31795 | __startTimestamp1487599559289__endTimestamp 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | ------- Summary: start ------- 06:05:59 INFO - PROCESS | 31795 | Number of tests: 6 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | [#0] dojo.html Cycles:165 Average:4769.04 Median:4892.09 stddev:512.19 (10.5%) stddev-sans-first:483.50 06:05:59 INFO - PROCESS | 31795 | Values: 2557.8 2585.6 2590.6 2623.6 2632.0 4777.5 4830.3 4831.4 4843.2 4869.0 4814.6 4836.9 4851.8 4877.6 4890.5 4247.3 4485.6 4574.1 4596.8 4614.2 4566.0 4574.5 4584.2 4591.9 4597.1 4244.9 4284.0 4394.4 4473.9 4484.3 4412.0 4429.4 4442.1 4487.1 4497.5 4376.5 4402.5 4411.6 4424.6 4445.1 4665.3 4690.6 4769.1 4797.4 4803.3 5214.2 5226.8 5257.3 5258.7 5265.8 5228.4 5240.1 5250.9 5262.4 5311.9 4567.7 4881.2 4892.1 4898.9 4900.0 5020.8 5072.1 5188.3 5247.4 5275.2 4902.5 5048.8 5083.2 5095.1 5103.2 5173.6 5186.6 5188.1 5189.4 5191.1 4866.7 5080.2 5123.7 5165.9 5174.9 4877.0 4931.9 4954.0 4973.0 4983.4 4228.0 4236.8 4249.8 4252.2 4355.6 3772.2 3825.4 3831.1 3846.1 3861.3 4493.9 4495.0 4498.7 4506.6 4522.5 4924.8 4972.7 5214.4 5240.1 5246.2 4472.8 4509.1 4512.2 4515.7 4567.4 4668.5 4717.0 4723.8 4725.7 4765.0 4605.8 4876.5 4922.6 4929.1 4971.7 4944.9 5143.9 5143.9 5170.5 5230.2 4805.6 4806.2 4823.9 4840.0 4857.2 5064.6 5081.1 5090.1 5090.8 5143.3 4932.0 4986.7 5195.3 5239.2 5245.9 5108.7 5117.6 5126.9 5127.1 5165.9 4892.5 4907.6 5086.2 5123.8 5150.7 4891.0 4949.8 4950.3 4952.9 4953.8 5140.7 5149.7 5156.3 5199.6 5209.9 4984.3 5016.6 5020.2 5040.5 5045.1 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | [#1] ext.html Cycles:180 Average:40604.33 Median:33442.08 stddev:68580.13 (205.1%) stddev-sans-first:68706.55 06:05:59 INFO - PROCESS | 31795 | Values: 541.9 548.4 555.2 589.5 601.8 33973.0 37774.0 37843.0 37862.6 37914.8 38223.2 39068.6 39091.4 39105.0 39170.0 7291.4 7525.4 9255.9 9719.7 9724.5 9370.4 9401.7 9432.0 9441.3 9454.0 9437.6 9532.0 9536.4 9544.0 9544.9 9412.7 9425.6 9478.1 9493.5 9503.8 9409.2 9432.7 9467.1 9490.1 9503.3 33129.8 33239.5 33311.0 33417.2 33465.8 3225.6 3338.6 3398.8 3414.6 3455.1 22332.9 22562.8 22661.5 22680.7 22705.2 3163.6 3287.5 3309.1 3434.6 3438.7 218597.0 219812.0 220805.0 221106.0 222110.0 39323.2 40159.4 45936.8 47274.0 47307.5 50220.2 50391.5 50463.2 50563.0 50857.0 12883.1 13018.6 13058.2 13062.3 13067.2 9290.7 9393.4 9402.2 9413.0 9570.9 15680.5 15793.2 15835.2 15885.2 15944.0 6538.5 6686.4 6714.8 6743.7 6775.5 750.7 751.2 771.0 779.0 779.8 25504.7 27032.3 27142.9 27176.0 27515.9 893.6 924.4 928.8 932.2 947.5 14314.0 14591.8 14630.9 14959.9 14970.0 299091.0 303931.0 304102.0 305287.0 305700.0 49491.0 49545.8 49868.0 50180.2 50302.7 1106.2 1139.3 1164.0 1171.6 1177.3 24857.4 25553.7 26553.4 26606.5 26838.9 41417.6 41762.0 41762.2 41854.6 41895.4 254345.0 255696.9 256398.0 256411.0 257207.0 22722.7 23801.0 23811.6 24635.9 24918.6 33271.2 33419.2 33465.0 33816.5 34397.5 27235.9 28730.7 29405.9 29416.0 30706.0 28525.9 29062.4 30097.4 30130.2 30161.4 29825.9 29970.9 30106.6 30187.5 30300.4 25782.6 26727.7 27226.5 27242.0 27297.8 20734.4 20937.8 21115.5 21213.8 21320.1 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | [#2] jquery.html Cycles:180 Average:27118.35 Median:240774.44 stddev:75987.09 (31.6%) stddev-sans-first:76173.73 06:05:59 INFO - PROCESS | 31795 | Values: 380.1 383.9 403.4 406.6 419.0 16109.7 17510.1 17656.4 17726.3 18415.3 16831.5 17686.9 18119.3 18173.7 18229.0 5149.7 5155.7 5299.8 5359.7 5363.2 5155.9 5281.7 5286.1 5286.6 5288.9 5196.3 5209.0 5227.0 5237.7 5248.9 5122.5 5183.7 5201.6 5217.2 5230.6 4633.8 4659.6 4682.4 4688.4 4690.9 20808.8 20858.7 21044.6 21063.2 21090.0 2163.3 2199.7 2225.9 2245.0 2269.3 13268.3 13428.2 13487.7 13628.3 14049.6 1607.3 1650.8 1670.6 1696.4 1737.1 72807.0 72839.6 74098.3 74186.3 75531.2 9588.0 9761.4 9858.2 10197.9 10484.1 14312.7 14336.2 14358.1 14394.5 14446.8 6782.2 6961.1 7064.6 7194.2 7199.8 6404.8 6435.7 6638.9 6649.6 6671.6 6494.7 6495.4 6521.9 6563.6 6736.5 1736.3 1736.9 1757.5 1852.2 1854.4 800.8 820.1 821.5 822.1 826.5 16792.9 16940.5 17184.3 19581.9 19756.0 957.6 958.9 958.9 959.4 965.9 9524.8 9714.2 9976.3 10018.9 10125.8 407167.0 465635.0 468810.8 476858.0 477644.0 91091.0 91346.5 91404.0 94211.0 94735.5 988.4 1016.2 1040.7 1042.5 1056.7 15156.7 15362.2 15695.6 15825.2 16047.3 39256.8 39526.8 39714.4 40405.6 40672.0 49625.5 49901.3 51314.7 57605.4 57750.4 5364.2 6503.2 6640.3 7630.7 7948.2 7210.2 9809.9 11035.6 11786.1 11802.0 9071.5 9551.9 9968.0 10071.1 10389.0 10145.4 10506.3 10633.0 10716.1 11024.9 5636.9 9589.2 9640.1 9924.5 11223.7 7599.4 7741.6 7911.2 7994.0 8183.5 9288.3 10404.4 10672.0 10794.2 10824.6 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | [#3] mootools.html Cycles:180 Average:5010.75 Median:4751.54 stddev:2821.74 (59.4%) stddev-sans-first:2816.22 06:05:59 INFO - PROCESS | 31795 | Values: 1347.4 1352.5 1354.4 1357.1 1365.6 7425.1 7667.3 7828.6 7911.9 7919.9 7399.9 7718.3 7722.2 7739.2 7746.4 2142.2 2185.2 2185.9 2189.1 2192.7 2119.9 2172.7 2173.8 2175.9 2191.4 2121.0 2143.8 2160.5 2173.5 2180.4 2139.1 2143.7 2151.3 2155.4 2157.8 1774.4 1828.7 2042.7 2098.3 2150.5 7610.5 7852.8 7864.9 7882.5 7915.9 4459.9 4658.1 4661.9 4665.3 4677.0 2958.8 2970.4 2995.8 3033.3 3041.7 1124.7 1154.6 1154.9 1159.6 1197.0 10400.1 10501.3 10571.1 10606.7 10628.8 4064.8 4123.9 4217.2 4234.5 4357.0 9104.6 9118.0 9143.8 9155.3 9168.8 4673.9 4706.0 4719.2 4720.5 4732.5 4120.7 4142.0 4147.9 4240.1 4241.4 3667.6 3699.0 3721.2 3809.4 3822.9 1252.3 1265.2 1265.7 1284.1 1292.5 744.6 775.7 782.5 784.2 790.0 4704.4 4849.8 4870.9 4898.0 4960.2 522.0 523.7 527.4 529.9 531.4 3003.5 3016.0 3069.6 3082.2 3125.6 8777.8 8925.9 9019.9 9044.5 9371.4 9270.5 9286.6 9308.2 9318.3 9353.9 7175.4 7327.6 7341.0 7353.5 7354.5 4770.6 4942.7 4966.8 4992.5 5029.1 8321.2 8477.8 8670.9 8715.3 8791.5 8639.5 8756.5 8758.4 8775.5 8799.6 6503.8 6619.3 6631.7 6638.5 6646.7 7274.3 7283.7 7302.7 7368.0 7391.5 6717.1 6733.3 6740.0 6744.6 6776.6 6579.8 6600.5 6607.9 6629.7 6639.6 5585.7 5692.3 5696.8 5699.5 5706.3 4148.6 4164.2 4186.7 4192.2 4283.4 4204.9 4243.1 4251.3 4349.1 4407.1 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | [#4] prototype.html Cycles:180 Average:4312.85 Median:4884.97 stddev:1242.86 (25.4%) stddev-sans-first:1210.18 06:05:59 INFO - PROCESS | 31795 | Values: 347.6 362.6 365.1 366.5 371.6 4847.5 4972.4 5067.3 5140.4 5142.5 4936.9 5022.4 5030.4 5032.0 5064.4 4092.3 4100.8 4113.1 4122.6 4130.4 3720.9 4105.1 4111.8 4113.5 4117.9 3859.4 3865.6 3867.2 3886.9 3921.9 3874.8 3879.6 3880.9 3901.2 3923.5 3413.6 3432.5 3437.5 3439.4 3447.3 4652.7 4885.6 4930.7 4938.6 4956.6 4421.1 4433.3 4433.7 4440.5 4453.0 5260.3 5273.2 5276.7 5277.7 5318.7 3525.8 3706.2 3736.7 3748.4 3758.3 5201.5 5348.9 5386.8 5463.2 5496.3 3799.2 3800.6 3809.3 3813.1 3846.8 3850.7 3887.7 3895.5 3898.3 3913.4 4274.7 4401.9 4410.1 4429.4 4444.9 5238.2 5245.4 5261.5 5270.9 5272.3 1279.7 1292.3 1292.5 1296.2 1300.3 815.9 866.0 872.3 884.1 884.1 4433.7 4444.7 4451.0 4457.4 4463.3 5237.7 5254.5 5260.4 5262.4 5283.9 4185.5 4419.5 4422.2 4436.3 4455.2 2742.2 2749.9 2750.3 2764.0 2794.4 4836.3 4874.0 4902.8 4914.2 4932.6 5083.5 5257.0 5338.8 5346.4 5403.9 4837.4 4860.3 4867.6 4872.9 4905.3 5131.9 5235.2 5236.0 5248.6 5269.0 4934.4 5119.8 5154.9 5190.2 5213.6 4784.9 4858.9 4884.4 4937.8 5011.5 3756.7 3782.9 3789.7 3797.3 3797.5 5395.4 5414.9 5445.3 5452.2 5462.6 5102.9 5165.4 5175.2 5188.0 5218.8 5198.7 5203.0 5209.2 5210.7 5218.9 5137.4 5138.5 5142.6 5161.4 5196.5 4976.4 5289.6 5306.8 5312.0 5333.1 5145.2 5178.2 5182.9 5203.9 5209.8 06:05:59 INFO - PROCESS | 31795 | 06:05:59 INFO - PROCESS | 31795 | [#5] yui.html Cycles:180 Average:14167.75 Median:27994.29 stddev:17760.11 (63.4%) stddev-sans-first:17780.93 06:05:59 INFO - PROCESS | 31795 | Values: 650.9 652.1 704.9 715.9 738.5 11780.9 13104.0 13115.0 13130.7 13143.7 12533.7 12650.1 12686.5 12699.9 12774.8 799.2 814.4 814.4 815.1 851.6 834.4 842.5 843.4 843.5 846.0 866.3 867.9 871.6 872.6 874.0 869.6 870.7 871.5 871.7 875.0 635.2 640.4 641.5 641.7 642.6 12629.6 12740.5 12750.4 12787.8 13550.1 9812.9 9835.5 9896.4 10377.0 11199.3 11814.0 11839.1 11853.9 11884.5 11938.2 4058.5 4087.8 4102.9 4148.0 4169.9 56035.2 57738.0 58317.4 59194.0 59686.4 9730.8 9752.9 9803.8 9884.6 9960.6 17166.7 17200.6 17313.3 17381.0 17549.8 5834.7 5982.5 6229.3 6258.5 6462.6 3200.0 3297.1 3312.9 3328.6 3410.6 2776.3 2877.5 3250.8 3385.5 3397.5 524.5 527.3 528.1 537.0 540.1 547.8 555.1 564.5 573.8 578.9 7912.6 7922.6 8472.4 8508.7 8715.8 627.3 631.2 636.8 640.3 643.4 3955.7 3998.9 4032.3 4128.8 4185.8 60844.0 62528.0 63603.9 64535.7 65085.7 54780.3 55011.0 55084.7 55093.7 55433.4 51483.0 52092.7 52233.2 52984.0 53031.5 7617.9 7928.5 8050.6 8056.4 8159.1 36135.3 36320.5 37105.0 37187.6 37931.8 40448.6 43900.6 44352.0 45174.3 45286.3 10110.3 10142.0 10295.3 10324.5 10941.1 11135.2 11177.8 11289.9 11333.9 11498.8 9815.9 9882.3 10136.2 10393.9 10577.6 9123.4 9586.8 9730.8 9778.8 9781.1 10091.5 10108.6 10265.7 10353.2 10672.4 8536.6 8772.3 8979.7 9077.8 9129.2 8057.6 8335.2 8687.2 8692.4 8916.5 06:05:59 INFO - PROCESS | 31795 | -------- Summary: end -------- 06:05:59 INFO - PROCESS | 31795 | 06:06:00 INFO - TEST-INFO | 31795: exit 0 06:06:00 INFO - TEST-OK | dromaeo_css | took 1120624ms 06:06:00 INFO - TEST-START | kraken 06:06:00 INFO - operating with platform_type : linux_ 06:06:00 INFO - Initialising browser for kraken test... 06:06:00 INFO - TEST-INFO | started process 32173 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCPDZ6i/profile http://localhost:47336/getInfo.html) 06:06:03 INFO - PROCESS | 32173 | Iterating over profile /tmp/tmpCPDZ6i/profile 06:06:03 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:03 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/crashes = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:03 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:03 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/extensions.ini = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:03 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:03 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/prefs.js = Mon Feb 20 2017 05:46:39 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/pluginreg.dat = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/extensions = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/content-prefs.sqlite = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/frequencyCap.json = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/places.sqlite-wal = Mon Feb 20 2017 06:06:04 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/sessionCheckpoints.json = Mon Feb 20 2017 06:06:03 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/permissions.sqlite = Mon Feb 20 2017 06:06:01 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | __metrics Screen width/height:1600/1200 06:06:04 INFO - PROCESS | 32173 | colorDepth:24 06:06:04 INFO - PROCESS | 32173 | Browser inner width/height: 1024/697 06:06:04 INFO - PROCESS | 32173 | __metrics 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/extensions.json = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/secmod.db = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/startupCache = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/safebrowsing = Mon Feb 20 2017 06:06:04 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/addons.json = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | Stat failure 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/blocklist.xml = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/key3.db = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/thumbnails = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/user.js = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/minidumps = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/places.sqlite = Mon Feb 20 2017 06:06:04 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/places.sqlite-shm = Mon Feb 20 2017 06:06:01 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/directoryLinks.json = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/cache2 = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/compatibility.ini = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/cert8.db = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/localstore.rdf = Mon Feb 20 2017 05:46:39 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/gmp = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/bookmarkbackups = Mon Feb 20 2017 06:06:02 GMT-0800 (PST) 06:06:04 INFO - PROCESS | 32173 | No birth date. Using mtime. 06:06:04 INFO - PROCESS | 32173 | Using date: /tmp/tmpCPDZ6i/profile/.parentlock = Mon Feb 20 2017 06:06:00 GMT-0800 (PST) 06:06:05 ERROR - PROCESS | 32173 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 06:06:06 INFO - TEST-INFO | 32173: exit 0 06:06:06 INFO - Browser initialized. 06:06:06 INFO - Running cycle 1/1 for kraken test... 06:06:06 INFO - TEST-INFO | started process 32302 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCPDZ6i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:06:18 INFO - PROCESS | 32302 | RSS: Main: 153337856 06:06:18 INFO - PROCESS | 32302 | 06:06:21 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47336/tests/kraken/driver.html?testName=audio-beat-detection) 06:06:22 INFO - PROCESS | 32302 | RSS: Main: 159514624 06:06:22 INFO - PROCESS | 32302 | 06:06:24 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47336/tests/kraken/driver.html?testName=audio-dft) 06:06:25 INFO - PROCESS | 32302 | RSS: Main: 158298112 06:06:25 INFO - PROCESS | 32302 | 06:06:28 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47336/tests/kraken/driver.html?testName=audio-fft) 06:06:29 INFO - PROCESS | 32302 | RSS: Main: 155250688 06:06:29 INFO - PROCESS | 32302 | 06:06:31 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47336/tests/kraken/driver.html?testName=audio-oscillator) 06:06:31 INFO - PROCESS | 32302 | RSS: Main: 155918336 06:06:31 INFO - PROCESS | 32302 | 06:06:34 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47336/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:06:34 INFO - PROCESS | 32302 | RSS: Main: 157421568 06:06:34 INFO - PROCESS | 32302 | 06:06:37 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47336/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:38 INFO - PROCESS | 32302 | RSS: Main: 161566720 06:06:38 INFO - PROCESS | 32302 | 06:06:42 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47336/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:42 INFO - PROCESS | 32302 | RSS: Main: 158298112 06:06:42 INFO - PROCESS | 32302 | 06:06:45 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47336/tests/kraken/driver.html?testName=json-parse-financial) 06:06:46 INFO - PROCESS | 32302 | RSS: Main: 159735808 06:06:46 INFO - PROCESS | 32302 | 06:06:47 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47336/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:48 INFO - PROCESS | 32302 | RSS: Main: 159162368 06:06:48 INFO - PROCESS | 32302 | 06:06:50 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:51 INFO - PROCESS | 32302 | RSS: Main: 159223808 06:06:51 INFO - PROCESS | 32302 | 06:06:53 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:54 INFO - PROCESS | 32302 | RSS: Main: 159551488 06:06:54 INFO - PROCESS | 32302 | 06:06:56 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:57 INFO - PROCESS | 32302 | RSS: Main: 160202752 06:06:57 INFO - PROCESS | 32302 | 06:07:00 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:07:01 INFO - PROCESS | 32302 | RSS: Main: 162873344 06:07:01 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | Cycle 1(1): loaded http://localhost:47336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47336/tests/kraken/driver.html?testName=ai-astar) 06:07:03 INFO - PROCESS | 32302 | RSS: Main: 164048896 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | __start_tp_report 06:07:03 INFO - PROCESS | 32302 | _x_x_mozilla_page_load 06:07:03 INFO - PROCESS | 32302 | _x_x_mozilla_page_load_details 06:07:03 INFO - PROCESS | 32302 | |i|pagename|runs| 06:07:03 INFO - PROCESS | 32302 | |0;ai-astar;108;116;116;118;117;117;117;115;119;115 06:07:03 INFO - PROCESS | 32302 | |1;audio-beat-detection;163;134;130;165;128;130;129;133;155;129 06:07:03 INFO - PROCESS | 32302 | |2;audio-dft;163;166;170;169;181;162;164;165;163;165 06:07:03 INFO - PROCESS | 32302 | |3;audio-fft;77;78;77;108;89;79;79;79;104;78 06:07:03 INFO - PROCESS | 32302 | |4;audio-oscillator;84;84;109;85;85;85;108;83;84;106 06:07:03 INFO - PROCESS | 32302 | |5;imaging-gaussian-blur;113;114;114;115;115;115;114;114;115;113 06:07:03 INFO - PROCESS | 32302 | |6;imaging-darkroom;149;150;152;150;150;151;151;152;151;150 06:07:03 INFO - PROCESS | 32302 | |7;imaging-desaturate;96;100;97;97;96;96;96;96;96;96 06:07:03 INFO - PROCESS | 32302 | |8;json-parse-financial;67;69;68;67;69;69;71;70;70;68 06:07:03 INFO - PROCESS | 32302 | |9;json-stringify-tinderbox;66;65;65;66;66;65;67;65;66;67 06:07:03 INFO - PROCESS | 32302 | |10;stanford-crypto-aes;91;94;90;90;91;89;90;97;91;92 06:07:03 INFO - PROCESS | 32302 | |11;stanford-crypto-ccm;139;149;147;146;141;143;139;148;184;158 06:07:03 INFO - PROCESS | 32302 | |12;stanford-crypto-pbkdf2;190;189;188;192;186;187;183;182;185;185 06:07:03 INFO - PROCESS | 32302 | |13;stanford-crypto-sha256-iterative;74;76;74;71;71;72;79;72;72;70 06:07:03 INFO - PROCESS | 32302 | __end_tp_report 06:07:03 INFO - PROCESS | 32302 | __start_cc_report 06:07:03 INFO - PROCESS | 32302 | _x_x_mozilla_cycle_collect,871 06:07:03 INFO - PROCESS | 32302 | __end_cc_report 06:07:03 INFO - PROCESS | 32302 | __startTimestamp1487599623570__endTimestamp 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | ------- Summary: start ------- 06:07:03 INFO - PROCESS | 32302 | Number of tests: 14 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#0] ai-astar Cycles:10 Average:115.80 Median:117.00 stddev:3.01 (2.6%) stddev-sans-first:1.32 06:07:03 INFO - PROCESS | 32302 | Values: 108.0 116.0 116.0 118.0 117.0 117.0 117.0 115.0 119.0 115.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:133.50 stddev:15.09 (11.3%) stddev-sans-first:13.42 06:07:03 INFO - PROCESS | 32302 | Values: 163.0 134.0 130.0 165.0 128.0 130.0 129.0 133.0 155.0 129.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#2] audio-dft Cycles:10 Average:166.80 Median:165.50 stddev:5.61 (3.4%) stddev-sans-first:5.78 06:07:03 INFO - PROCESS | 32302 | Values: 163.0 166.0 170.0 169.0 181.0 162.0 164.0 165.0 163.0 165.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#3] audio-fft Cycles:10 Average:84.80 Median:78.50 stddev:11.74 (15.0%) stddev-sans-first:12.10 06:07:03 INFO - PROCESS | 32302 | Values: 77.0 78.0 77.0 108.0 89.0 79.0 79.0 79.0 104.0 78.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:84.00 stddev:11.33 (13.5%) stddev-sans-first:11.71 06:07:03 INFO - PROCESS | 32302 | Values: 84.0 84.0 109.0 85.0 85.0 85.0 108.0 83.0 84.0 106.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.71 06:07:03 INFO - PROCESS | 32302 | Values: 113.0 114.0 114.0 115.0 115.0 115.0 114.0 114.0 115.0 113.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:0.97 (0.6%) stddev-sans-first:0.83 06:07:03 INFO - PROCESS | 32302 | Values: 149.0 150.0 152.0 150.0 150.0 151.0 151.0 152.0 151.0 150.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:96.00 stddev:1.26 (1.3%) stddev-sans-first:1.32 06:07:03 INFO - PROCESS | 32302 | Values: 96.0 100.0 97.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:69.00 stddev:1.32 (1.9%) stddev-sans-first:1.22 06:07:03 INFO - PROCESS | 32302 | Values: 67.0 69.0 68.0 67.0 69.0 69.0 71.0 70.0 70.0 68.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 06:07:03 INFO - PROCESS | 32302 | Values: 66.0 65.0 65.0 66.0 66.0 65.0 67.0 65.0 66.0 67.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:91.00 stddev:2.37 (2.6%) stddev-sans-first:2.51 06:07:03 INFO - PROCESS | 32302 | Values: 91.0 94.0 90.0 90.0 91.0 89.0 90.0 97.0 91.0 92.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:147.50 stddev:13.41 (9.1%) stddev-sans-first:13.68 06:07:03 INFO - PROCESS | 32302 | Values: 139.0 149.0 147.0 146.0 141.0 143.0 139.0 148.0 184.0 158.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.70 Median:187.50 stddev:3.13 (1.7%) stddev-sans-first:3.08 06:07:03 INFO - PROCESS | 32302 | Values: 190.0 189.0 188.0 192.0 186.0 187.0 183.0 182.0 185.0 185.0 06:07:03 INFO - PROCESS | 32302 | 06:07:03 INFO - PROCESS | 32302 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.00 stddev:2.73 (3.7%) stddev-sans-first:2.87 06:07:03 INFO - PROCESS | 32302 | Values: 74.0 76.0 74.0 71.0 71.0 72.0 79.0 72.0 72.0 70.0 06:07:03 INFO - PROCESS | 32302 | -------- Summary: end -------- 06:07:03 INFO - PROCESS | 32302 | 06:07:04 INFO - TEST-INFO | 32302: exit 0 06:07:04 INFO - TEST-OK | kraken | took 64606ms 06:07:04 INFO - SUITE-END | took 1185s 06:07:04 INFO - Completed test suite (00:19:45) 06:07:04 INFO - javascript benchmark 06:07:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6316.881299224656, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2557.7825884799795, 2585.560454722697, 2590.5855063189892, 2623.5671114266147, 2632.039305653436, 4777.546133117357, 4830.323754674339, 4831.420229572447, 4843.225083986563, 4868.951310486894, 4814.614830813535, 4836.854894353175, 4851.781669824857, 4877.585405240558, 4890.510948905107, 4247.341662042373, 4485.573870482299, 4574.130915126116, 4596.839110631128, 4614.215583350839, 4566.041131362418, 4574.542545745416, 4584.19776539106, 4591.908161836761, 4597.126545956257, 4244.851430199959, 4284.036091879333, 4394.362817391492, 4473.9105217895785, 4484.304932735431, 4412.029353542215, 4429.402030725843, 4442.133783912139, 4487.057717879249, 4497.527759585229, 4376.540463251376, 4402.471703395581, 4411.580899814512, 4424.646028317727, 4445.088756804847, 4665.300204969248, 4690.64820138491, 4769.117713223065, 4797.448293446263, 4803.279508073782, 5214.191800270964, 5226.764795584208, 5257.316548848664, 5258.6581872178185, 5265.76304066318, 5228.398734145546, 5240.135377662681, 5250.9474905250845, 5262.394824595182, 5311.920321195185, 4567.725936443824, 4881.194602890554, 4892.094962431962, 4898.877528061791, 4899.975500122477, 5020.7740651670765, 5072.137736584789, 5188.299579556711, 5247.422783493813, 5275.208718692227, 4902.48523904992, 5048.798048078109, 5083.237514372874, 5095.108356037678, 5103.157978933508, 5173.611979101583, 5186.559142472932, 5188.066148093304, 5189.42916279209, 5191.117510023229, 4866.683665561727, 5080.161773307437, 5123.666961647481, 5165.870853228699, 5174.948250517522, 4876.951455437098, 4931.939632978927, 4953.950460495421, 4972.950270497248, 4983.426905905867, 4227.957720422818, 4236.830526778895, 4249.830006799755, 4252.192083504125, 4355.60799528044, 3772.2078363543965, 3825.445310429972, 3831.080540670275, 3846.0961674006585, 3861.343571592836, 4493.865184044484, 4495.0, 4498.6626003049905, 4506.616937560344, 4522.4573051234065, 4924.75376231187, 4972.701637901738, 5214.426413094557, 5240.135377662643, 5246.160614301693, 4472.8210871565425, 4509.098180363875, 4512.2329204035395, 4515.661325400608, 4567.429071366079, 4668.4631267404, 4716.95283047172, 4723.7638118093955, 4725.740084295397, 4765.046990601824, 4605.815767369268, 4876.463589005207, 4922.556969872586, 4929.112759703145, 4971.701697898137, 4944.92582611254, 5143.8714032148, 5143.948560514347, 5170.5346518813485, 5230.189320655152, 4805.567498924974, 4806.182948898766, 4823.855284341475, 4839.959408727141, 4857.247126695368, 5064.569511591409, 5081.0854046272025, 5090.134677104827, 5090.796368145381, 5143.254228136974, 4931.9753401232765, 4986.675866068694, 5195.3246078009615, 5239.161734122522, 5245.868853278698, 5108.719020453762, 5117.565006974449, 5126.948730512647, 5127.128388173945, 5165.896682066413, 4892.461829198856, 4907.607391408607, 5086.160783470834, 5123.795048198031, 5150.665206761699, 4890.951090489049, 4949.752512374295, 4950.306957026091, 4952.8761780955765, 4953.777080031479, 5140.691558506502, 5149.716765577929, 5156.329677141947, 5199.55803756685, 5209.869753256199, 4984.277279794482, 5016.623753218596, 5020.196768517019, 5040.470750571138, 5045.066662667418], "value": 4735.061000590588, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.8699512117092, 548.4159370270661, 555.1783360626273, 589.5254320272179, 601.7833579911231, 33973.0, 37774.0, 37843.0, 37862.621373786256, 37914.8104259479, 38223.23553528928, 39068.609313906854, 39091.41362879559, 39105.0, 39170.0, 7291.416686665068, 7525.435592330569, 9255.907440925606, 9719.708408747749, 9724.465154416504, 9370.39092458988, 9401.717948461523, 9431.95284023579, 9441.339106262565, 9454.0, 9437.622495100188, 9531.95234023829, 9536.380135291218, 9543.952280238624, 9544.90455095447, 9412.717618471457, 9425.622975080989, 9478.099580539838, 9493.525323733855, 9503.809923801555, 9409.200217981479, 9432.669856554987, 9467.100625440573, 9490.050994900525, 9503.334766566342, 33129.83435082809, 33239.50140747891, 33311.0, 33417.16457088592, 33465.83267083673, 3225.645179030305, 3338.6327503974785, 3398.8130652814084, 3414.590249170091, 3455.101673564866, 22332.888335558382, 22562.774372256397, 22661.546769064527, 22680.65979010316, 22705.20531781396, 3163.636181839072, 3287.474004159347, 3309.12308238317, 3434.553508043938, 3438.6905178534053, 218597.0, 219812.0, 220805.0, 221106.0, 222110.0, 39323.213535729126, 40159.3976090353, 45936.770316148206, 47274.0, 47307.526924730315, 50220.24669629958, 50391.49608503941, 50463.24305135426, 50563.0, 50857.0, 12883.098183127091, 13018.609441716764, 13058.151220170848, 13062.281574513492, 13067.150635208682, 9290.674826381179, 9393.389429687048, 9402.200812930841, 9413.0, 9570.856437153448, 15680.529584112495, 15793.157231698404, 15835.208239587975, 15885.205739712968, 15944.0, 6538.542302038907, 6686.3952651363325, 6714.832129196711, 6743.718693448229, 6775.491838112062, 750.6622020090981, 751.2449987762263, 770.9746037769753, 778.9727359542388, 779.8479296537121, 25504.744952550238, 27032.32419189536, 27142.86428567884, 27176.0, 27515.86242068777, 893.5978809535735, 924.4360939826701, 928.832810094176, 932.2309094996655, 947.4930911962067, 14314.0, 14591.78112328316, 14630.926845365704, 14959.925200374146, 14970.0, 299091.0, 303931.0, 304102.0, 305287.0, 305700.0, 49491.0, 49545.75227123914, 49868.0, 50180.24729629058, 50302.74848625807, 1106.1703722208345, 1139.3050239354154, 1164.0338519029185, 1171.6250799744, 1177.252444697623, 24857.378565536008, 25553.744462555136, 26553.423781006204, 26606.467870642868, 26838.852891769002, 41417.58582414137, 41762.0, 41762.164756704085, 41854.58145418507, 41895.37156942587, 254345.0, 255696.93666848502, 256398.0, 256411.0, 257207.0, 22722.659160112282, 23801.0, 23811.64282535798, 24635.876820615784, 24918.626220607068, 33271.16822079467, 33419.16452088717, 33465.0, 33816.49275260824, 34397.484037738956, 27235.86382068077, 28730.712692872803, 29405.944552159268, 29416.0, 30706.0, 28525.857370713013, 29062.41875162527, 30097.3980520384, 30130.24674383158, 30161.396772064876, 29825.850870746377, 29970.850145749133, 30106.580815453428, 30187.547186791777, 30300.393992120476, 25782.613260801478, 26727.732722672525, 27226.45547089087, 27242.0, 27297.79411764728, 20734.377968660963, 20937.790622093584, 21115.47211319668, 21213.787862121182, 21320.14719411206], "value": 16359.410122401838, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.0802059017178, 383.91170030892914, 403.4210907347956, 406.64215490368474, 418.96857735669835, 16109.677806443879, 17510.124493775325, 17656.38202662907, 17726.290948362068, 18415.26338946444, 16831.495055148363, 17686.911565442188, 18119.275228990857, 18173.7273940891, 18229.0, 5149.716765577892, 5155.71643559604, 5299.814506492274, 5359.705216213107, 5363.195520671911, 5155.922661160085, 5281.6830990140525, 5286.074936886049, 5286.550643195333, 5288.92066619001, 5196.324477817879, 5209.011201907546, 5227.0, 5237.685738855662, 5248.947510524884, 5122.487751224867, 5183.714895680736, 5201.55786758126, 5217.243499692523, 5230.607704422184, 4633.837815676435, 4659.627229821606, 4682.391289132425, 4688.367070445514, 4690.882727931795, 20808.79191208099, 20858.687119693215, 21044.5791084179, 21063.157473701034, 21090.0, 2163.2644900733826, 2199.714037175173, 2225.888705564715, 2244.955100897973, 2269.3305474884946, 13268.3365831709, 13428.19430834153, 13487.662808429868, 13628.250446225453, 14049.578512644637, 1607.26869274481, 1650.801903771543, 1670.5656529302348, 1696.4062578097692, 1737.1140718233792, 72806.99123096456, 72839.63580182118, 74098.25901740967, 74186.25813741848, 75531.24468755352, 9587.952060239653, 9761.414315141114, 9858.162056225157, 10197.9490102549, 10484.056434920742, 14312.713745725026, 14336.21150836714, 14358.138511689123, 14394.496192633207, 14446.783298250535, 6782.220044694924, 6961.095057642576, 7064.646767661699, 7194.208637050025, 7199.820004499929, 6404.775832845828, 6435.74257029723, 6638.933610663831, 6649.567778094505, 6671.566348187353, 6494.740210391531, 6495.415412612888, 6521.934780652227, 6563.6389998549585, 6736.5284430090405, 1736.3401907275406, 1736.8957862528036, 1757.4551888914477, 1852.2220667319757, 1854.4158590044092, 800.803803068252, 820.0528389709895, 821.5481485183126, 822.0669540072062, 826.4917075998218, 16792.888374806334, 16940.491785246464, 17184.31262749476, 19581.90209048974, 19756.0, 957.5690939077443, 958.8945216026232, 958.9041095890494, 959.3668179001826, 965.8937516873139, 9524.761880953032, 9714.174295184988, 9976.251781116445, 10018.949905250573, 10125.797484050425, 407167.0, 465635.0, 468810.7748686407, 476858.0, 477644.0, 91091.0, 91346.54326728456, 91404.0, 94211.0, 94735.52632236933, 988.3674448353204, 1016.1870503597092, 1040.7346126737634, 1042.489180301662, 1056.6618682021833, 15156.696866062397, 15362.155081470626, 15695.607609809846, 15825.208739562746, 16047.277872495531, 39256.80371598124, 39526.80236598913, 39714.40428393519, 40405.595944040186, 40672.0, 49625.50374496209, 49901.25148122854, 51314.74342628263, 57605.423945761686, 57750.4224957745, 5364.249005139206, 6503.154589903283, 6640.335966403321, 7630.694772209049, 7948.211652378169, 7210.1708303545765, 9809.920908699894, 11035.558577656804, 11786.116041296766, 11802.0, 9071.50106744109, 9551.90448095538, 9968.0, 10071.143952764098, 10389.013043760831, 10145.391276523433, 10506.317089389166, 10633.0, 10716.08913242352, 11024.944875275893, 5636.85907852307, 9589.184919281939, 9640.132388084825, 9924.454155021544, 11223.66329010131, 7599.354054905395, 7741.5742134183165, 7911.248431399008, 7993.960030199812, 8183.508989460652, 9288.34981551312, 10404.427756473468, 10672.0, 10794.244402891722, 10824.56701731922], "value": 8508.621279175748, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.366737633312, 1352.4725357110726, 1354.3972932045244, 1357.0975301424553, 1365.6244532753499, 7425.1461081975585, 7667.348275396584, 7828.608569571513, 7911.920880791183, 7919.881201781971, 7399.889001664979, 7718.266764657348, 7722.189170137125, 7739.187385324544, 7746.4190185736015, 2142.1645558232303, 2185.2024011235944, 2185.912563497458, 2189.058704756954, 2192.725909261342, 2119.947001324964, 2172.73927128744, 2173.7500187478404, 2175.869447833127, 2191.397365724423, 2121.0349291072603, 2143.7534683511362, 2160.5138843760105, 2173.4566358410398, 2180.411288951982, 2139.1336508713994, 2143.6998820165113, 2151.3008272311563, 2155.3964889830795, 2157.8165855902266, 1774.4055741326672, 1828.6616975859508, 2042.714020037196, 2098.3075585056895, 2150.473134082144, 7610.543367397974, 7852.803679907991, 7864.921350786476, 7882.487638303492, 7915.920840791576, 4459.933101003455, 4658.114958157939, 4661.930071048937, 4665.346851440801, 4676.953230467686, 2958.778091643135, 2970.376220993572, 2995.775316851223, 3033.2568520712566, 3041.741451976585, 1124.6569796212152, 1154.6189757380043, 1154.9133814963911, 1159.605734050427, 1196.9581064662782, 10400.115990140846, 10501.31741436804, 10571.101456376216, 10606.681799546026, 10628.840567391419, 4064.7561146331313, 4123.876283711494, 4217.198732240926, 4234.513031001413, 4357.0, 9104.635814567344, 9118.0, 9143.817123657489, 9155.313351498664, 9168.770780730401, 4673.906521869543, 4706.0, 4719.174144524694, 4720.527947205252, 4732.455767586703, 4120.711550191518, 4141.979290103591, 4147.854825081107, 4240.067185219247, 4241.448611680524, 3667.6149004354693, 3698.9630103699155, 3721.2371463849845, 3809.3904975203836, 3822.9235415291537, 1252.3425201769144, 1265.202922159034, 1265.6519457149172, 1284.1011292095384, 1292.515306759965, 744.6388501576731, 775.6780935911646, 782.5462469973435, 784.1648644194029, 789.9881501777478, 4704.388429504143, 4849.781759820817, 4870.853874383774, 4897.975510122498, 4960.23116416956, 521.9686818790884, 523.6517715719081, 527.3882296536076, 529.9178627312773, 531.44994930247, 3003.4593773121046, 3015.9698403016127, 3069.63164420271, 3082.1832214463184, 3125.6092988376454, 8777.780555486164, 8925.910740892637, 9019.864702029476, 9044.547772611342, 9371.390859594105, 9270.53647317645, 9286.62853485853, 9308.162265396148, 9318.347715659838, 9353.924298705735, 7175.390091842148, 7327.560346379234, 7341.0, 7353.485256032026, 7354.485186037033, 4770.6183505318795, 4942.728149951788, 4966.801327946841, 4992.475790041994, 5029.06962211992, 8321.16788321163, 8477.830443391222, 8670.913290867264, 8715.302775777796, 8791.5164665944, 8639.524826134622, 8756.518391488275, 8758.430702004349, 8775.517346545746, 8799.604017819085, 6503.837404064936, 6619.304972977768, 6631.701573429111, 6638.535302528777, 6646.734130634721, 7274.30894065063, 7283.708651654087, 7302.6713797878165, 7368.042154519878, 7391.482596218213, 6717.1267735194115, 6733.293004234485, 6740.0, 6744.62904540255, 6776.627285499345, 6579.835504112435, 6600.504962127956, 6607.867842643216, 6629.668516574248, 6639.634820084942, 5585.664860108406, 5692.288463942007, 5696.829095127153, 5699.487046165699, 5706.343770466449, 4148.585141485948, 4164.167166566638, 4186.748795072305, 4192.224438478891, 4283.443152390294, 4204.936925946052, 4243.130158317475, 4251.256030194641, 4349.130173965156, 4407.118576284692], "value": 4008.383751197251, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.5707501235973, 362.5594902193834, 365.0617911966247, 366.4594722783894, 371.58011447064814, 4847.515248475151, 4972.428170760364, 5067.290579318898, 5140.383154021518, 5142.460041695619, 4936.901261974758, 5022.422421421537, 5030.446650868402, 5032.0, 5064.366954130734, 4092.3247664135383, 4100.815463304143, 4113.095119073799, 4122.56713045129, 4130.401091841696, 3720.9069773255615, 4105.137921036571, 4111.753294802306, 4113.526944401402, 4117.855875044374, 3859.4017927221184, 3865.555461121965, 3867.245887052026, 3886.9222615547674, 3921.8822635548872, 3874.786886721229, 3879.631435013669, 3880.8835734927998, 3901.1612503311926, 3923.4507168996365, 3413.6245013048547, 3432.48512723091, 3437.4500079987333, 3439.398105331582, 3447.3450044491465, 4652.674312798105, 4885.560299573055, 4930.654854160211, 4938.58022068121, 4956.603471722254, 4421.137878113769, 4433.2685106957315, 4433.6674749393605, 4440.53374395687, 4453.0, 5260.289860868772, 5273.182656688219, 5276.736163191864, 5277.70972596507, 5318.707471089088, 3525.770824896399, 3706.2031663192543, 3736.6637002669618, 3748.3881930769758, 3758.267137908109, 5201.45384734605, 5348.930213957224, 5386.845261895234, 5463.235147079412, 5496.285482887239, 3799.2211596622624, 3800.6199380062326, 3809.3333666608232, 3813.103920578662, 3846.8461261549223, 3850.7497012694644, 3887.6695480883877, 3895.4935858338235, 3898.2983063048355, 3913.3934240192816, 4274.700770946066, 4401.889952751143, 4410.117976404667, 4429.379886815848, 4444.933326000112, 5238.240455133984, 5245.370555533361, 5261.526462618306, 5270.92093618596, 5272.261883336336, 1279.7120647854158, 1292.2827830553945, 1292.5394304435927, 1296.2093123194843, 1300.2653500772033, 815.9347252219927, 865.9696910608099, 872.2847265242567, 884.1114679746942, 884.1158841158841, 4433.7118087324225, 4444.711093778894, 4450.9985253317745, 4457.442819647544, 4463.308187230984, 5237.659552129099, 5254.527092561688, 5260.368755749258, 5262.447443018505, 5283.894322113536, 4185.476815398075, 4419.513853476115, 4422.226110430662, 4436.290193569013, 4455.242608756519, 2742.204760619438, 2749.9037533686223, 2750.2849259192353, 2763.9723602763715, 2794.3712664650698, 4836.250381190921, 4874.0, 4902.823322402669, 4914.238293064581, 4932.556069953722, 5083.517065878736, 5256.973715131477, 5338.759755810997, 5346.385165705994, 5403.891922161613, 4837.443693975168, 4860.343853579792, 4867.561919427198, 4872.902541949141, 4905.313256144214, 5131.948680513147, 5235.188545775411, 5235.973820131027, 5248.60635452335, 5269.0, 4934.358533390637, 5119.795208191779, 5154.922676159786, 5190.195519694454, 5213.556847667991, 4784.928226076542, 4858.854234372974, 4884.389451318585, 4937.8271760488215, 5011.473795251592, 3756.722714277159, 3782.9432558512194, 3789.677877380343, 3797.2974999625158, 3797.525309336333, 5395.406505284337, 5414.864628384322, 5445.346558413015, 5452.154915988028, 5462.617616766947, 5102.856800144023, 5165.431802501646, 5175.16281719999, 5188.0, 5218.765155567932, 5198.662086964335, 5202.97398513005, 5209.218617207449, 5210.739463026757, 5218.869528261824, 5137.383513978197, 5138.458462461172, 5142.562882155059, 5161.354830646169, 5196.506331898464, 4976.353074100489, 5289.636050258629, 5306.8407947761625, 5311.973440132774, 5333.066713325075, 5145.202493613346, 5178.249153872592, 5182.870428239174, 5203.869903252449, 5209.843704688866], "value": 3929.380533433439, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.9023646453031, 652.1066139389031, 704.8942658601209, 715.8890371992342, 738.4904415952993, 11780.941095294522, 13104.0, 13115.0, 13130.737385252301, 13143.6714082148, 12533.749325013494, 12650.114491985565, 12686.4925402984, 12699.936500317508, 12774.808377874338, 799.2447137455092, 814.3770015937812, 814.4339683919649, 815.1400272712316, 851.5571902610639, 834.4159088637947, 842.5366048673236, 843.409613270713, 843.4939036578065, 845.953472559009, 866.3112825303898, 867.8524650809346, 871.6426265231256, 872.5593575244493, 874.0254616103031, 869.6347534035721, 870.7474832298626, 871.5032431514039, 871.7428358634218, 874.97812554686, 635.2345423764353, 640.4332166033095, 641.4964253061371, 641.708022849607, 642.601587016053, 12629.621111366674, 12740.490380384774, 12750.426230819636, 12787.808182877265, 13550.119242249222, 9812.90187098127, 9835.508224588742, 9896.356736812084, 10377.014183652542, 11199.272047316897, 11814.0, 11839.13555835185, 11853.94073029638, 11884.5432962639, 11938.22401543897, 4058.492688413948, 4087.816048277835, 4102.917941641151, 4147.958520414787, 4169.874903752892, 56035.15947260794, 57738.0, 58317.4168258312, 59194.0, 59686.40313596852, 9730.75673108178, 9752.902470975341, 9803.803923921481, 9884.55519501624, 9960.551775170135, 17166.742498862528, 17200.569985750455, 17313.30746770115, 17380.957142571486, 17549.824501755076, 5834.67909264986, 5982.49148822355, 6229.345918678564, 6258.499320054385, 6462.612243265419, 3200.0, 3297.0768184907784, 3312.8981368692425, 3328.5506456628527, 3410.6248312685584, 2776.3059235191204, 2877.525208340642, 3250.8049517029053, 3385.4752513360468, 3397.5073614325784, 524.5043433954876, 527.343457395545, 528.1417696243604, 536.9892602147935, 540.0522083743084, 547.7781498493117, 555.1395337227282, 564.5455408396199, 573.8135106090538, 578.9044807606698, 7912.564808935449, 7922.643481043367, 8472.406931514735, 8508.659653613786, 8715.782105447288, 627.2567008095422, 631.2078341681253, 636.7707625254905, 640.2828831708423, 643.3630705601514, 3955.6835453164254, 3998.9200215995515, 4032.274190645653, 4128.752274863517, 4185.832566697298, 60844.0, 62528.0, 63603.91168706492, 64535.67732161309, 65085.67457162684, 54780.330753694514, 55011.0, 55084.72457637686, 55093.72453137708, 55433.44566554283, 51483.0, 52092.73953630208, 52233.216501752504, 52984.0, 53031.469685302654, 7617.923820761943, 7928.524288542706, 8050.637721302673, 8056.355491560778, 8159.061707903667, 36135.27729445449, 36320.45519317265, 37105.0, 37187.628123718416, 37931.81034094812, 40448.59551404566, 43900.56099438965, 44352.0, 45174.32238516491, 45286.32070518879, 10110.342827716175, 10142.0, 10295.279330446796, 10324.48377581103, 10941.12471002302, 11135.164862635173, 11177.832332515181, 11289.943550282196, 11333.886661133283, 11498.82751758741, 9815.901840981498, 9882.308238423242, 10136.18910487174, 10393.896061039293, 10577.576896924036, 9123.361364704408, 9586.808263834824, 9730.80538389214, 9778.75443240251, 9781.070798274153, 10091.495425228562, 10108.646197383056, 10265.692029239135, 10353.223508237063, 10672.413017284123, 8536.573171341284, 8772.254358379609, 8979.685711000084, 9077.77305567366, 9129.224015958718, 8057.556834374166, 8335.249827515552, 8687.218150366498, 8692.391532592657, 8916.465012099296], "value": 6119.611872401932, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 116.0, 118.0, 117.0, 117.0, 117.0, 115.0, 119.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 134.0, 130.0, 165.0, 128.0, 130.0, 129.0, 133.0, 155.0, 129.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 170.0, 169.0, 181.0, 162.0, 164.0, 165.0, 163.0, 165.0], "value": 166.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 108.0, 89.0, 79.0, 79.0, 79.0, 104.0, 78.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 109.0, 85.0, 85.0, 85.0, 108.0, 83.0, 84.0, 106.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 115.0, 115.0, 115.0, 114.0, 114.0, 115.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 152.0, 150.0, 150.0, 151.0, 151.0, 152.0, 151.0, 150.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 100.0, 97.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 67.0, 69.0, 69.0, 71.0, 70.0, 70.0, 68.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 66.0, 65.0, 67.0, 65.0, 66.0, 67.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 90.0, 90.0, 91.0, 89.0, 90.0, 97.0, 91.0, 92.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 149.0, 147.0, 146.0, 141.0, 143.0, 139.0, 148.0, 184.0, 158.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 189.0, 188.0, 192.0, 186.0, 187.0, 183.0, 182.0, 185.0, 185.0], "value": 186.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 71.0, 71.0, 72.0, 79.0, 72.0, 72.0, 70.0], "value": 73.1, "unit": "score"}]}]} 06:07:04 INFO - Return code: 0 06:07:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:07:04 ERROR - # TBPL SUCCESS # 06:07:04 INFO - Running post-action listener: _resource_record_post_action 06:07:04 INFO - [mozharness: 2017-02-20 14:07:04.897525Z] Finished run-tests step (success) 06:07:04 INFO - Running post-run listener: _resource_record_post_run 06:07:04 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 361709568; Read time: 0; Write time: 205588 06:07:04 INFO - TinderboxPrint: CPU usage
13.5% 06:07:04 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:07:04 INFO - TinderboxPrint: I/O write bytes / time
361,709,568 / 205,588 06:07:04 INFO - TinderboxPrint: CPU idle
8,289.7 (86.5%) 06:07:04 INFO - TinderboxPrint: CPU user
1,237.6 (12.9%) 06:07:04 INFO - TinderboxPrint: Swap in / out
0 / 0 06:07:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105668608; Read time: 0; Write time: 94180 06:07:04 INFO - run-tests - Wall time: 1186s; CPU: 14.0%; Read bytes: 0; Write bytes: 256040960; Read time: 0; Write time: 111408 06:07:05 INFO - Running post-run listener: _upload_blobber_files 06:07:05 INFO - Blob upload gear active. 06:07:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:07:05 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 06:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:07:05 INFO - (blobuploader) - INFO - Open directory for files ... 06:07:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:07:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:07:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:06 INFO - (blobuploader) - INFO - Done attempting. 06:07:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:07:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:10 INFO - (blobuploader) - INFO - Done attempting. 06:07:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:07:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:07:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:11 INFO - (blobuploader) - INFO - Done attempting. 06:07:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:07:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:07:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:12 INFO - (blobuploader) - INFO - Done attempting. 06:07:12 INFO - (blobuploader) - INFO - Iteration through files over. 06:07:12 INFO - Return code: 0 06:07:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:07:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:07:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7325355bb025a4b55661e78b7ef94e346377f1d6ec31ce3358adcfdbd24c78b6b8189f13234be16c166404f1a447c2b666a22d20e73124bd8a1e59e23fb4ca13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acb39491c707df71d493ef252b385847ce449a5f932cba3fba0111cf30c52e10d700d01f772d6081c72b4ebc805bc82d2432cf9f539bfb0b2e17b0f9ab513e87", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ee5ff68e59fed0d666ad5217eb8373f3ecc647ff14622eda6be8eee769a6fb52eb6f5cbc5a5b23f8d06f48879f962260a23aadb298c066099b3ac7eaae5a74d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85c494192baaa8580c61698796c65000f4e47271974039e99c0a6cb7ce2fedf6bac8bd6295fa5167f228df4cbd64370a5513b739edf09c7bf026d3d64d0ff614"} 06:07:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:07:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:07:12 INFO - Contents: 06:07:12 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7325355bb025a4b55661e78b7ef94e346377f1d6ec31ce3358adcfdbd24c78b6b8189f13234be16c166404f1a447c2b666a22d20e73124bd8a1e59e23fb4ca13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acb39491c707df71d493ef252b385847ce449a5f932cba3fba0111cf30c52e10d700d01f772d6081c72b4ebc805bc82d2432cf9f539bfb0b2e17b0f9ab513e87", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ee5ff68e59fed0d666ad5217eb8373f3ecc647ff14622eda6be8eee769a6fb52eb6f5cbc5a5b23f8d06f48879f962260a23aadb298c066099b3ac7eaae5a74d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85c494192baaa8580c61698796c65000f4e47271974039e99c0a6cb7ce2fedf6bac8bd6295fa5167f228df4cbd64370a5513b739edf09c7bf026d3d64d0ff614"} 06:07:12 INFO - Running post-run listener: copy_logs_to_upload_dir 06:07:12 INFO - Copying logs to upload dir... 06:07:12 INFO - mkdir: /builds/slave/test/build/upload/logs 06:07:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.515931 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-20 06:07:11.946485) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 06:07:11.950491) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7325355bb025a4b55661e78b7ef94e346377f1d6ec31ce3358adcfdbd24c78b6b8189f13234be16c166404f1a447c2b666a22d20e73124bd8a1e59e23fb4ca13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acb39491c707df71d493ef252b385847ce449a5f932cba3fba0111cf30c52e10d700d01f772d6081c72b4ebc805bc82d2432cf9f539bfb0b2e17b0f9ab513e87", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ee5ff68e59fed0d666ad5217eb8373f3ecc647ff14622eda6be8eee769a6fb52eb6f5cbc5a5b23f8d06f48879f962260a23aadb298c066099b3ac7eaae5a74d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85c494192baaa8580c61698796c65000f4e47271974039e99c0a6cb7ce2fedf6bac8bd6295fa5167f228df4cbd64370a5513b739edf09c7bf026d3d64d0ff614"} build_url:https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011173 build_url: 'https://queue.taskcluster.net/v1/task/fthK8qOgSiis2UNk5Rmg8A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7325355bb025a4b55661e78b7ef94e346377f1d6ec31ce3358adcfdbd24c78b6b8189f13234be16c166404f1a447c2b666a22d20e73124bd8a1e59e23fb4ca13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acb39491c707df71d493ef252b385847ce449a5f932cba3fba0111cf30c52e10d700d01f772d6081c72b4ebc805bc82d2432cf9f539bfb0b2e17b0f9ab513e87", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7ee5ff68e59fed0d666ad5217eb8373f3ecc647ff14622eda6be8eee769a6fb52eb6f5cbc5a5b23f8d06f48879f962260a23aadb298c066099b3ac7eaae5a74d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85c494192baaa8580c61698796c65000f4e47271974039e99c0a6cb7ce2fedf6bac8bd6295fa5167f228df4cbd64370a5513b739edf09c7bf026d3d64d0ff614"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 06:07:11.977626) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:07:11.978153) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487598371.195624-242990490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:07:12.061545) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 06:07:12.061849) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 06:07:12.062137) ========= ========= Total master_lag: 0.17 =========