builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1487692923.15 results: success (0) revision: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.147837) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.148228) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.148510) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.162422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.162741) ========= 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=03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-21 08:02:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.2M=0s 2017-02-21 08:02:03 (38.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082950 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.258727) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.259045) ========= 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=03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018176 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:03.307925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-21 08:02:03.310261) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa --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 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa --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=03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-21 08:02:03,367 truncating revision to first 12 chars 2017-02-21 08:02:03,367 Setting DEBUG logging. 2017-02-21 08:02:03,367 attempt 1/10 2017-02-21 08:02:03,367 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/74cef34e1927?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 08:02:04,453 unpacking tar archive at: mozilla-central-74cef34e1927/testing/mozharness/ program finished with exit code 0 elapsedTime=1.374993 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-21 08:02:04.698047) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:04.698378) ========= script_repo_revision: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:04.698729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:04.698988) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 08:02:04.708768) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-21 08:02:04.709177) ========= /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=03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:02:04 INFO - MultiFileLogger online at 20170221 08:02:04 in /builds/slave/test 08:02:04 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 08:02:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:02:04 INFO - {'append_to_log': False, 08:02:04 INFO - 'base_work_dir': '/builds/slave/test', 08:02:04 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:02:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:02:04 INFO - 'branch': 'Firefox-Non-PGO', 08:02:04 INFO - 'buildbot_json_path': 'buildprops.json', 08:02:04 INFO - 'config_files': ('talos/linux_config.py',), 08:02:04 INFO - 'default_actions': ('clobber', 08:02:04 INFO - 'read-buildbot-config', 08:02:04 INFO - 'download-and-extract', 08:02:04 INFO - 'populate-webroot', 08:02:04 INFO - 'create-virtualenv', 08:02:04 INFO - 'install', 08:02:04 INFO - 'run-tests'), 08:02:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:02:04 INFO - 'download_minidump_stackwalk': True, 08:02:04 INFO - 'download_symbols': 'ondemand', 08:02:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:02:04 INFO - 'tooltool.py': '/tools/tooltool.py', 08:02:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:02:04 INFO - '/tools/misc-python/virtualenv.py')}, 08:02:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:02:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:02:04 INFO - 'gecko_profile': False, 08:02:04 INFO - 'gecko_profile_interval': 0, 08:02:04 INFO - 'installer_path': 'installer.exe', 08:02:04 INFO - 'log_level': 'info', 08:02:04 INFO - 'log_name': 'talos', 08:02:04 INFO - 'log_to_console': True, 08:02:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:02:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:02:04 INFO - 'opt_config_files': (), 08:02:04 INFO - 'pip_index': False, 08:02:04 INFO - 'suite': 'dromaeojs-e10s', 08:02:04 INFO - 'system_bits': '32', 08:02:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:02:04 INFO - 'title': 'talos-linux64-ix-047', 08:02:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:02:04 INFO - 'use_talos_json': True, 08:02:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:02:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:02:04 INFO - 'work_dir': 'build'} 08:02:04 INFO - [mozharness: 2017-02-21 16:02:04.861225Z] Running clobber step. 08:02:04 INFO - Running pre-action listener: _resource_record_pre_action 08:02:04 INFO - Running main action method: clobber 08:02:04 INFO - rmtree: /builds/slave/test/build 08:02:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:02:05 INFO - Running post-action listener: _resource_record_post_action 08:02:05 INFO - [mozharness: 2017-02-21 16:02:05.770636Z] Finished clobber step (success) 08:02:05 INFO - [mozharness: 2017-02-21 16:02:05.770734Z] Running read-buildbot-config step. 08:02:05 INFO - Running pre-action listener: _resource_record_pre_action 08:02:05 INFO - Running main action method: read_buildbot_config 08:02:05 INFO - Using buildbot properties: 08:02:05 INFO - { 08:02:05 INFO - "project": "", 08:02:05 INFO - "product": "firefox", 08:02:05 INFO - "who": "ffxbld@noreply.mozilla.org", 08:02:05 INFO - "installer_path": "public/build/target.tar.bz2", 08:02:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:02:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:02:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:02:05 INFO - "stage_platform": "linux64", 08:02:05 INFO - "basedir": "/builds/slave/test", 08:02:05 INFO - "buildnumber": 709, 08:02:05 INFO - "platform": "ubuntu64_hw", 08:02:05 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:02:05 INFO - "slavebuilddir": "test", 08:02:05 INFO - "taskId": "SNEq-CMcQu6R9yw62ljbpw", 08:02:05 INFO - "branch": "mozilla-central", 08:02:05 INFO - "script_repo_revision": "production", 08:02:05 INFO - "revision": "74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa", 08:02:05 INFO - "slavename": "talos-linux64-ix-047", 08:02:05 INFO - "repo_path": "mozilla-central" 08:02:05 INFO - } 08:02:05 INFO - Finding installer, test and symbols from parent task. 08:02:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SNEq-CMcQu6R9yw62ljbpw'}, attempt #1 08:02:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SNEq-CMcQu6R9yw62ljbpw'}, attempt #1 08:02:06 INFO - Task dependencies: fWcnSoQdR1K5iB-xv_YRIg 08:02:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg'}, attempt #1 08:02:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2 08:02:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/test_packages.json 08:02:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.crashreporter-symbols.zip 08:02:07 INFO - Running post-action listener: _resource_record_post_action 08:02:07 INFO - [mozharness: 2017-02-21 16:02:07.168182Z] Finished read-buildbot-config step (success) 08:02:07 INFO - [mozharness: 2017-02-21 16:02:07.168398Z] Running download-and-extract step. 08:02:07 INFO - Running pre-action listener: _resource_record_pre_action 08:02:07 INFO - Running main action method: download_and_extract 08:02:07 INFO - mkdir: /builds/slave/test/build/tests 08:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:07 INFO - trying https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.test_packages.json 08:02:07 INFO - Downloading https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:02:07 INFO - Downloaded 896 bytes. 08:02:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:02:07 INFO - Using the following test package requirements: 08:02:07 INFO - {u'common': [u'target.common.tests.zip'], 08:02:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:02:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:02:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:02:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:02:07 INFO - u'mozbase': [u'target.common.tests.zip'], 08:02:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:02:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:02:07 INFO - u'web-platform': [u'target.common.tests.zip', 08:02:07 INFO - u'target.web-platform.tests.zip'], 08:02:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:02:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:02:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.common.tests.zip 08:02:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:02:07 INFO - Fetch https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.common.tests.zip into memory 08:02:10 INFO - Expected file size: 57165821 08:02:10 INFO - Obtained file size: 57165821 08:02:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:02:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.common.tests.zip 08:02:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:02:20 INFO - Fetch https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.common.tests.zip into memory 08:02:21 INFO - Expected file size: 57165821 08:02:21 INFO - Obtained file size: 57165821 08:02:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.talos.tests.zip 08:02:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:02:33 INFO - Fetch https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.talos.tests.zip into memory 08:02:33 INFO - Expected file size: 14404319 08:02:33 INFO - Obtained file size: 14404319 08:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:34 INFO - trying https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2 08:02:34 INFO - Downloading https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:02:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:02:35 INFO - Downloaded 61504102 bytes. 08:02:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2 08:02:35 INFO - mkdir: /builds/slave/test/properties 08:02:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:02:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:02:35 INFO - Contents: 08:02:35 INFO - build_url:https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2 08:02:35 INFO - Running post-action listener: _resource_record_post_action 08:02:35 INFO - Running post-action listener: set_extra_try_arguments 08:02:35 INFO - [mozharness: 2017-02-21 16:02:35.609233Z] Finished download-and-extract step (success) 08:02:35 INFO - [mozharness: 2017-02-21 16:02:35.609329Z] Running populate-webroot step. 08:02:35 INFO - Running pre-action listener: _resource_record_pre_action 08:02:35 INFO - Running main action method: populate_webroot 08:02:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:02:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:02:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:02:35 INFO - '--tppagecycles', 08:02:35 INFO - '7'], 08:02:35 INFO - 'tests': ['tsvgm']}}, 08:02:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:02:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:02:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:02:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:02:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:02:35 INFO - 'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:02:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:02:35 INFO - 'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['damp', 'tps']}, 08:02:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:02:35 INFO - 'tests': ['damp', 'tps']}, 08:02:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['dromaeo_dom']}, 08:02:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:02:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['basic_compositor_video']}, 08:02:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:02:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['a11yr', 08:02:35 INFO - 'ts_paint', 08:02:35 INFO - 'tpaint', 08:02:35 INFO - 'sessionrestore', 08:02:35 INFO - 'sessionrestore_no_auto_restore', 08:02:35 INFO - 'tabpaint']}, 08:02:35 INFO - 'other-e10s': {'tests': ['a11yr', 08:02:35 INFO - 'ts_paint', 08:02:35 INFO - 'tpaint', 08:02:35 INFO - 'sessionrestore', 08:02:35 INFO - 'sessionrestore_no_auto_restore', 08:02:35 INFO - 'tabpaint']}, 08:02:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:02:35 INFO - 'ts_paint', 08:02:35 INFO - 'tpaint', 08:02:35 INFO - 'sessionrestore', 08:02:35 INFO - 'sessionrestore_no_auto_restore', 08:02:35 INFO - 'tabpaint']}, 08:02:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:02:35 INFO - 'ts_paint', 08:02:35 INFO - 'tpaint', 08:02:35 INFO - 'sessionrestore', 08:02:35 INFO - 'sessionrestore_no_auto_restore', 08:02:35 INFO - 'tabpaint']}, 08:02:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['a11yr', 08:02:35 INFO - 'ts_paint', 08:02:35 INFO - 'tpaint', 08:02:35 INFO - 'sessionrestore', 08:02:35 INFO - 'sessionrestore_no_auto_restore', 08:02:35 INFO - 'tabpaint']}, 08:02:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['a11yr', 08:02:35 INFO - 'ts_paint', 08:02:35 INFO - 'tpaint', 08:02:35 INFO - 'sessionrestore', 08:02:35 INFO - 'sessionrestore_no_auto_restore', 08:02:35 INFO - 'tabpaint']}, 08:02:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['tsvgx', 08:02:35 INFO - 'tsvgr_opacity', 08:02:35 INFO - 'tart', 08:02:35 INFO - 'tscrollx', 08:02:35 INFO - 'cart', 08:02:35 INFO - 'tsvg_static']}, 08:02:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:02:35 INFO - 'tsvgr_opacity', 08:02:35 INFO - 'tart', 08:02:35 INFO - 'tscrollx', 08:02:35 INFO - 'cart', 08:02:35 INFO - 'tsvg_static']}, 08:02:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:02:35 INFO - 'talos_options': ['--disable-e10s'], 08:02:35 INFO - 'tests': ['tp5o']}, 08:02:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:02:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:02:35 INFO - 'talos_options': ['--disable-e10s', 08:02:35 INFO - '--xperf_path', 08:02:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:35 INFO - 'tests': ['tp5n']}, 08:02:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:02:35 INFO - 'talos_options': ['--xperf_path', 08:02:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:35 INFO - 'tests': ['tp5n']}}, 08:02:35 INFO - 'talos.zip': {'path': '', 08:02:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:02:35 INFO - Running post-action listener: _resource_record_post_action 08:02:35 INFO - [mozharness: 2017-02-21 16:02:35.622483Z] Finished populate-webroot step (success) 08:02:35 INFO - [mozharness: 2017-02-21 16:02:35.622573Z] Running create-virtualenv step. 08:02:35 INFO - Running pre-action listener: _resource_record_pre_action 08:02:35 INFO - Running main action method: create_virtualenv 08:02:35 INFO - Creating virtualenv /builds/slave/test/build/venv 08:02:35 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 08:02:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:02:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:02:35 INFO - Using real prefix '/usr' 08:02:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:02:36 INFO - Installing distribute.............................................................................................................................................................................................done. 08:02:38 INFO - Installing pip.................done. 08:02:38 INFO - Return code: 0 08:02:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:02:38 INFO - Reading from file tmpfile_stdout 08:02:38 INFO - Output received: 08:02:38 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) 08:02:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:02:38 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')]} 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:38 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')]} 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:38 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:38 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 08:02:38 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 08:02:38 INFO - Using env: {'DISPLAY': ':0', 08:02:38 INFO - 'HOME': '/home/cltbld', 08:02:38 INFO - 'LANG': 'en_US.UTF-8', 08:02:38 INFO - 'LANGUAGE': 'en_US:en', 08:02:38 INFO - 'LOGNAME': 'cltbld', 08:02:38 INFO - 'MAIL': '/var/mail/cltbld', 08:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:38 INFO - 'MOZ_NO_REMOTE': '1', 08:02:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:38 INFO - 'NO_EM_RESTART': '1', 08:02:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:38 INFO - 'PWD': '/builds/slave/test', 08:02:38 INFO - 'SHELL': '/bin/bash', 08:02:38 INFO - 'SHLVL': '1', 08:02:38 INFO - 'TERM': 'linux', 08:02:38 INFO - 'TMOUT': '86400', 08:02:38 INFO - 'USER': 'cltbld', 08:02:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:38 INFO - '_': '/tools/buildbot/bin/python'} 08:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:38 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 08:02:38 INFO - Cleaning up... 08:02:38 INFO - Return code: 0 08:02:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:02:38 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')]} 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:38 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')]} 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:38 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:38 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 08:02:38 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 08:02:38 INFO - Using env: {'DISPLAY': ':0', 08:02:38 INFO - 'HOME': '/home/cltbld', 08:02:38 INFO - 'LANG': 'en_US.UTF-8', 08:02:38 INFO - 'LANGUAGE': 'en_US:en', 08:02:38 INFO - 'LOGNAME': 'cltbld', 08:02:38 INFO - 'MAIL': '/var/mail/cltbld', 08:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:38 INFO - 'MOZ_NO_REMOTE': '1', 08:02:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:38 INFO - 'NO_EM_RESTART': '1', 08:02:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:38 INFO - 'PWD': '/builds/slave/test', 08:02:38 INFO - 'SHELL': '/bin/bash', 08:02:38 INFO - 'SHLVL': '1', 08:02:38 INFO - 'TERM': 'linux', 08:02:38 INFO - 'TMOUT': '86400', 08:02:38 INFO - 'USER': 'cltbld', 08:02:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:38 INFO - '_': '/tools/buildbot/bin/python'} 08:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:38 INFO - Downloading/unpacking psutil>=3.1.1 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:38 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 08:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:02:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:41 INFO - Installing collected packages: psutil 08:02:41 INFO - Running setup.py install for psutil 08:02:41 INFO - building 'psutil._psutil_linux' extension 08:02:41 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 08:02:42 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 08:02:42 INFO - building 'psutil._psutil_posix' extension 08:02:42 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 08:02:42 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 08:02:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:42 INFO - Successfully installed psutil 08:02:42 INFO - Cleaning up... 08:02:42 INFO - Return code: 0 08:02:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:42 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:42 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 08:02:42 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 08:02:42 INFO - Using env: {'DISPLAY': ':0', 08:02:42 INFO - 'HOME': '/home/cltbld', 08:02:42 INFO - 'LANG': 'en_US.UTF-8', 08:02:42 INFO - 'LANGUAGE': 'en_US:en', 08:02:42 INFO - 'LOGNAME': 'cltbld', 08:02:42 INFO - 'MAIL': '/var/mail/cltbld', 08:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:42 INFO - 'MOZ_NO_REMOTE': '1', 08:02:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:42 INFO - 'NO_EM_RESTART': '1', 08:02:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:42 INFO - 'PWD': '/builds/slave/test', 08:02:42 INFO - 'SHELL': '/bin/bash', 08:02:42 INFO - 'SHLVL': '1', 08:02:42 INFO - 'TERM': 'linux', 08:02:42 INFO - 'TMOUT': '86400', 08:02:42 INFO - 'USER': 'cltbld', 08:02:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:42 INFO - '_': '/tools/buildbot/bin/python'} 08:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:02:42 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 08:02:42 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 08:02:42 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 08:02:42 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 08:02:42 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 08:02:42 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 08:02:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:02:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:02:44 INFO - Installing collected packages: mozsystemmonitor 08:02:44 INFO - Running setup.py install for mozsystemmonitor 08:02:44 INFO - Successfully installed mozsystemmonitor 08:02:44 INFO - Cleaning up... 08:02:44 INFO - Return code: 0 08:02:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:02:44 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')]} 08:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:44 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')]} 08:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:44 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:44 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 08:02:44 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 08:02:44 INFO - Using env: {'DISPLAY': ':0', 08:02:44 INFO - 'HOME': '/home/cltbld', 08:02:44 INFO - 'LANG': 'en_US.UTF-8', 08:02:44 INFO - 'LANGUAGE': 'en_US:en', 08:02:44 INFO - 'LOGNAME': 'cltbld', 08:02:44 INFO - 'MAIL': '/var/mail/cltbld', 08:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:44 INFO - 'MOZ_NO_REMOTE': '1', 08:02:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:44 INFO - 'NO_EM_RESTART': '1', 08:02:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:44 INFO - 'PWD': '/builds/slave/test', 08:02:44 INFO - 'SHELL': '/bin/bash', 08:02:44 INFO - 'SHLVL': '1', 08:02:44 INFO - 'TERM': 'linux', 08:02:44 INFO - 'TMOUT': '86400', 08:02:44 INFO - 'USER': 'cltbld', 08:02:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:44 INFO - '_': '/tools/buildbot/bin/python'} 08:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:45 INFO - Downloading/unpacking jsonschema==2.5.1 08:02:45 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 08:02:45 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 08:02:45 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 08:02:45 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 08:02:45 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 08:02:45 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 08:02:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:02:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:47 INFO - Downloading functools32-3.2.3-2.tar.gz 08:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:02:47 INFO - warning: no files found matching '*.txt' 08:02:47 INFO - no previously-included directories found matching 'build' 08:02:47 INFO - no previously-included directories found matching 'dist' 08:02:47 INFO - no previously-included directories found matching '.git*' 08:02:47 INFO - Installing collected packages: jsonschema, functools32 08:02:47 INFO - Running setup.py install for functools32 08:02:47 INFO - warning: no files found matching '*.txt' 08:02:47 INFO - no previously-included directories found matching 'build' 08:02:47 INFO - no previously-included directories found matching 'dist' 08:02:47 INFO - no previously-included directories found matching '.git*' 08:02:47 INFO - Successfully installed jsonschema functools32 08:02:47 INFO - Cleaning up... 08:02:47 INFO - Return code: 0 08:02:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:02:47 INFO - Using env: {'DISPLAY': ':0', 08:02:47 INFO - 'HOME': '/home/cltbld', 08:02:47 INFO - 'LANG': 'en_US.UTF-8', 08:02:47 INFO - 'LANGUAGE': 'en_US:en', 08:02:47 INFO - 'LOGNAME': 'cltbld', 08:02:47 INFO - 'MAIL': '/var/mail/cltbld', 08:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:47 INFO - 'MOZ_NO_REMOTE': '1', 08:02:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:47 INFO - 'NO_EM_RESTART': '1', 08:02:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:47 INFO - 'PWD': '/builds/slave/test', 08:02:47 INFO - 'SHELL': '/bin/bash', 08:02:47 INFO - 'SHLVL': '1', 08:02:47 INFO - 'TERM': 'linux', 08:02:47 INFO - 'TMOUT': '86400', 08:02:47 INFO - 'USER': 'cltbld', 08:02:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:47 INFO - '_': '/tools/buildbot/bin/python'} 08:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:02:47 INFO - Cleaning up... 08:02:47 INFO - Return code: 0 08:02:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:02:47 INFO - Using env: {'DISPLAY': ':0', 08:02:47 INFO - 'HOME': '/home/cltbld', 08:02:47 INFO - 'LANG': 'en_US.UTF-8', 08:02:47 INFO - 'LANGUAGE': 'en_US:en', 08:02:47 INFO - 'LOGNAME': 'cltbld', 08:02:47 INFO - 'MAIL': '/var/mail/cltbld', 08:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:47 INFO - 'MOZ_NO_REMOTE': '1', 08:02:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:47 INFO - 'NO_EM_RESTART': '1', 08:02:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:47 INFO - 'PWD': '/builds/slave/test', 08:02:47 INFO - 'SHELL': '/bin/bash', 08:02:47 INFO - 'SHLVL': '1', 08:02:47 INFO - 'TERM': 'linux', 08:02:47 INFO - 'TMOUT': '86400', 08:02:47 INFO - 'USER': 'cltbld', 08:02:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:47 INFO - '_': '/tools/buildbot/bin/python'} 08:02:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:48 INFO - Downloading/unpacking blobuploader==1.2.4 08:02:48 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 08:02:48 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 08:02:48 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 08:02:48 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 08:02:48 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 08:02:48 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 08:02:50 INFO - Downloading blobuploader-1.2.4.tar.gz 08:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:02:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:02:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:50 INFO - Downloading docopt-0.6.1.tar.gz 08:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:02:50 INFO - Installing collected packages: blobuploader, requests, docopt 08:02:50 INFO - Running setup.py install for blobuploader 08:02:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:02:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:02:50 INFO - Running setup.py install for requests 08:02:51 INFO - Running setup.py install for docopt 08:02:51 INFO - Successfully installed blobuploader requests docopt 08:02:51 INFO - Cleaning up... 08:02:51 INFO - Return code: 0 08:02:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:51 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:51 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 08:02:51 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 08:02:51 INFO - Using env: {'DISPLAY': ':0', 08:02:51 INFO - 'HOME': '/home/cltbld', 08:02:51 INFO - 'LANG': 'en_US.UTF-8', 08:02:51 INFO - 'LANGUAGE': 'en_US:en', 08:02:51 INFO - 'LOGNAME': 'cltbld', 08:02:51 INFO - 'MAIL': '/var/mail/cltbld', 08:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:51 INFO - 'MOZ_NO_REMOTE': '1', 08:02:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:51 INFO - 'NO_EM_RESTART': '1', 08:02:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:51 INFO - 'PWD': '/builds/slave/test', 08:02:51 INFO - 'SHELL': '/bin/bash', 08:02:51 INFO - 'SHLVL': '1', 08:02:51 INFO - 'TERM': 'linux', 08:02:51 INFO - 'TMOUT': '86400', 08:02:51 INFO - 'USER': 'cltbld', 08:02:51 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:51 INFO - '_': '/tools/buildbot/bin/python'} 08:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:51 INFO - Running setup.py (path:/tmp/pip-hN1BcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:51 INFO - Running setup.py (path:/tmp/pip-wAczxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:51 INFO - Running setup.py (path:/tmp/pip-Tc30Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:51 INFO - Running setup.py (path:/tmp/pip-_DkWVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:51 INFO - Running setup.py (path:/tmp/pip-fCr5CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:52 INFO - Running setup.py (path:/tmp/pip-9QcTCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:52 INFO - Running setup.py (path:/tmp/pip-bPBHqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:52 INFO - Running setup.py (path:/tmp/pip-cCeGBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:52 INFO - Running setup.py (path:/tmp/pip-gtcxj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:52 INFO - Running setup.py (path:/tmp/pip-PpiTYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:52 INFO - Running setup.py (path:/tmp/pip-ZwBSqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:52 INFO - Running setup.py (path:/tmp/pip-TwcOf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:52 INFO - Running setup.py (path:/tmp/pip-bQn6wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:52 INFO - Running setup.py (path:/tmp/pip-Fzqjqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:52 INFO - Running setup.py (path:/tmp/pip-ISmFqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:52 INFO - Running setup.py (path:/tmp/pip-uqGpul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:52 INFO - Running setup.py (path:/tmp/pip-sc9zti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:02:52 INFO - Running setup.py install for manifestparser 08:02:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Running setup.py install for mozcrash 08:02:53 INFO - Running setup.py install for mozdebug 08:02:53 INFO - Running setup.py install for mozdevice 08:02:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Running setup.py install for mozfile 08:02:53 INFO - Running setup.py install for mozhttpd 08:02:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Running setup.py install for mozinfo 08:02:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Running setup.py install for mozInstall 08:02:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Running setup.py install for mozleak 08:02:53 INFO - Running setup.py install for mozlog 08:02:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:02:53 INFO - Running setup.py install for moznetwork 08:02:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:02:54 INFO - Running setup.py install for mozprocess 08:02:54 INFO - Running setup.py install for mozprofile 08:02:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:02:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:02:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:02:54 INFO - Running setup.py install for mozrunner 08:02:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:02:54 INFO - Running setup.py install for mozscreenshot 08:02:54 INFO - Running setup.py install for moztest 08:02:54 INFO - Running setup.py install for mozversion 08:02:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:02:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:02:54 INFO - Cleaning up... 08:02:54 INFO - Return code: 0 08:02:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:54 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:54 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 08:02:54 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 08:02:54 INFO - Using env: {'DISPLAY': ':0', 08:02:54 INFO - 'HOME': '/home/cltbld', 08:02:54 INFO - 'LANG': 'en_US.UTF-8', 08:02:54 INFO - 'LANGUAGE': 'en_US:en', 08:02:54 INFO - 'LOGNAME': 'cltbld', 08:02:54 INFO - 'MAIL': '/var/mail/cltbld', 08:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:54 INFO - 'MOZ_NO_REMOTE': '1', 08:02:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:54 INFO - 'NO_EM_RESTART': '1', 08:02:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:54 INFO - 'PWD': '/builds/slave/test', 08:02:54 INFO - 'SHELL': '/bin/bash', 08:02:54 INFO - 'SHLVL': '1', 08:02:54 INFO - 'TERM': 'linux', 08:02:54 INFO - 'TMOUT': '86400', 08:02:54 INFO - 'USER': 'cltbld', 08:02:54 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:54 INFO - '_': '/tools/buildbot/bin/python'} 08:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:54 INFO - Running setup.py (path:/tmp/pip-lJzAXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:55 INFO - Running setup.py (path:/tmp/pip-P3XzAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:55 INFO - Running setup.py (path:/tmp/pip-vVgAMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:55 INFO - Running setup.py (path:/tmp/pip-Qz204G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:55 INFO - Running setup.py (path:/tmp/pip-qMpAtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:55 INFO - Running setup.py (path:/tmp/pip-loV96_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:55 INFO - Running setup.py (path:/tmp/pip-mqtEGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:55 INFO - Running setup.py (path:/tmp/pip-53Winr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:55 INFO - Running setup.py (path:/tmp/pip-OPfTZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:55 INFO - Running setup.py (path:/tmp/pip-mnwooD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:55 INFO - Running setup.py (path:/tmp/pip-elVNNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:55 INFO - Running setup.py (path:/tmp/pip-RZSa5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:55 INFO - Running setup.py (path:/tmp/pip-1ku_8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:55 INFO - Running setup.py (path:/tmp/pip-UKXEqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:55 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)) 08:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:55 INFO - Running setup.py (path:/tmp/pip-Gjquhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:56 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)) 08:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:56 INFO - Running setup.py (path:/tmp/pip-X0wDX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:56 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)) 08:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:56 INFO - Running setup.py (path:/tmp/pip-bLkPIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:56 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)) 08:02:56 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)) 08:02:56 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)) 08:02:56 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)) 08:02:56 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)) 08:02:56 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)) 08:02:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:56 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 08:02:56 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 08:02:56 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 08:02:56 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 08:02:56 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 08:02:56 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 08:02:58 INFO - Downloading blessings-1.6.tar.gz 08:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:02:58 INFO - Installing collected packages: blessings 08:02:58 INFO - Running setup.py install for blessings 08:02:58 INFO - Successfully installed blessings 08:02:58 INFO - Cleaning up... 08:02:58 INFO - Return code: 0 08:02:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:58 INFO - Reading from file tmpfile_stdout 08:02:58 INFO - Current package versions: 08:02:58 INFO - argparse == 1.2.1 08:02:58 INFO - blessings == 1.6 08:02:58 INFO - blobuploader == 1.2.4 08:02:58 INFO - docopt == 0.6.1 08:02:58 INFO - functools32 == 3.2.3-2 08:02:58 INFO - jsonschema == 2.5.1 08:02:58 INFO - manifestparser == 1.1 08:02:58 INFO - mozInstall == 1.12 08:02:58 INFO - mozcrash == 1.0 08:02:58 INFO - mozdebug == 0.1 08:02:58 INFO - mozdevice == 0.49 08:02:58 INFO - mozfile == 1.2 08:02:58 INFO - mozhttpd == 0.7 08:02:58 INFO - mozinfo == 0.9 08:02:58 INFO - mozleak == 0.1 08:02:58 INFO - mozlog == 3.4 08:02:58 INFO - moznetwork == 0.27 08:02:58 INFO - mozprocess == 0.25 08:02:58 INFO - mozprofile == 0.28 08:02:58 INFO - mozrunner == 6.13 08:02:58 INFO - mozscreenshot == 0.1 08:02:58 INFO - mozsystemmonitor == 0.3 08:02:58 INFO - moztest == 0.8 08:02:58 INFO - mozversion == 1.4 08:02:58 INFO - psutil == 3.1.1 08:02:58 INFO - requests == 1.2.3 08:02:58 INFO - wsgiref == 0.1.2 08:02:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:58 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:58 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 08:02:58 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 08:02:58 INFO - Using env: {'DISPLAY': ':0', 08:02:58 INFO - 'HOME': '/home/cltbld', 08:02:58 INFO - 'LANG': 'en_US.UTF-8', 08:02:58 INFO - 'LANGUAGE': 'en_US:en', 08:02:58 INFO - 'LOGNAME': 'cltbld', 08:02:58 INFO - 'MAIL': '/var/mail/cltbld', 08:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:58 INFO - 'MOZ_NO_REMOTE': '1', 08:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:58 INFO - 'NO_EM_RESTART': '1', 08:02:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:58 INFO - 'PWD': '/builds/slave/test', 08:02:58 INFO - 'SHELL': '/bin/bash', 08:02:58 INFO - 'SHLVL': '1', 08:02:58 INFO - 'TERM': 'linux', 08:02:58 INFO - 'TMOUT': '86400', 08:02:58 INFO - 'USER': 'cltbld', 08:02:58 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:58 INFO - '_': '/tools/buildbot/bin/python'} 08:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 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)) 08:02:58 INFO - Cleaning up... 08:02:58 INFO - Return code: 0 08:02:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:58 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 0x235c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2454a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, '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': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', '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 08:02:58 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 08:02:58 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 08:02:58 INFO - Using env: {'DISPLAY': ':0', 08:02:58 INFO - 'HOME': '/home/cltbld', 08:02:58 INFO - 'LANG': 'en_US.UTF-8', 08:02:58 INFO - 'LANGUAGE': 'en_US:en', 08:02:58 INFO - 'LOGNAME': 'cltbld', 08:02:58 INFO - 'MAIL': '/var/mail/cltbld', 08:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:58 INFO - 'MOZ_NO_REMOTE': '1', 08:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:58 INFO - 'NO_EM_RESTART': '1', 08:02:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:58 INFO - 'PWD': '/builds/slave/test', 08:02:58 INFO - 'SHELL': '/bin/bash', 08:02:58 INFO - 'SHLVL': '1', 08:02:58 INFO - 'TERM': 'linux', 08:02:58 INFO - 'TMOUT': '86400', 08:02:58 INFO - 'USER': 'cltbld', 08:02:58 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:58 INFO - '_': '/tools/buildbot/bin/python'} 08:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:02:59 INFO - Cleaning up... 08:02:59 INFO - Return code: 0 08:02:59 INFO - Running post-action listener: _resource_record_post_action 08:02:59 INFO - Running post-action listener: _start_resource_monitoring 08:02:59 INFO - Starting resource monitoring. 08:02:59 INFO - [mozharness: 2017-02-21 16:02:59.184411Z] Finished create-virtualenv step (success) 08:02:59 INFO - [mozharness: 2017-02-21 16:02:59.184922Z] Running install step. 08:02:59 INFO - Running pre-action listener: _resource_record_pre_action 08:02:59 INFO - Running main action method: install 08:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:59 INFO - Reading from file tmpfile_stdout 08:02:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:02:59 INFO - Reading from file tmpfile_stdout 08:02:59 INFO - Output received: 08:02:59 INFO - Usage: mozinstall [options] installer 08:02:59 INFO - Options: 08:02:59 INFO - -h, --help show this help message and exit 08:02:59 INFO - -d DEST, --destination=DEST 08:02:59 INFO - Directory to install application into. [default: 08:02:59 INFO - "/builds/slave/test"] 08:02:59 INFO - --app=APP Application being installed. [default: firefox] 08:02:59 INFO - mkdir: /builds/slave/test/build/application 08:02:59 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'] 08:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:03:14 INFO - Reading from file tmpfile_stdout 08:03:14 INFO - Output received: 08:03:14 INFO - /builds/slave/test/build/application/firefox/firefox 08:03:14 INFO - Running post-action listener: _resource_record_post_action 08:03:14 INFO - [mozharness: 2017-02-21 16:03:14.865297Z] Finished install step (success) 08:03:14 INFO - [mozharness: 2017-02-21 16:03:14.865574Z] Running run-tests step. 08:03:14 INFO - Running pre-action listener: _resource_record_pre_action 08:03:14 INFO - Running main action method: run_tests 08:03:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:03:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:03:14 INFO - Python 2.7.3 08:03:14 INFO - Return code: 0 08:03:14 INFO - grabbing minidump binary from tooltool 08:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:14 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 0x25f7290>, '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 0x25f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f8040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:03:14 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 08:03:14 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 08:03:14 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 08:03:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:03:14 INFO - Return code: 0 08:03:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:03:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:03:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:03:14 INFO - ENV: RUST_BACKTRACE is now 1 08:03:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:03:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:03:14 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/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 08:03:14 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/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 08:03:14 INFO - Using env: {'DISPLAY': ':0', 08:03:14 INFO - 'HOME': '/home/cltbld', 08:03:14 INFO - 'LANG': 'en_US.UTF-8', 08:03:14 INFO - 'LANGUAGE': 'en_US:en', 08:03:14 INFO - 'LOGNAME': 'cltbld', 08:03:14 INFO - 'MAIL': '/var/mail/cltbld', 08:03:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:03:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:14 INFO - 'MOZ_NO_REMOTE': '1', 08:03:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:03:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:03:14 INFO - 'NO_EM_RESTART': '1', 08:03:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:14 INFO - 'PWD': '/builds/slave/test', 08:03:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:03:14 INFO - 'RUST_BACKTRACE': '1', 08:03:14 INFO - 'SHELL': '/bin/bash', 08:03:14 INFO - 'SHLVL': '1', 08:03:14 INFO - 'TERM': 'linux', 08:03:14 INFO - 'TMOUT': '86400', 08:03:14 INFO - 'USER': 'cltbld', 08:03:14 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254', 08:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:14 INFO - '_': '/tools/buildbot/bin/python'} 08:03:14 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/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 08:03:15 INFO - mozversion application_buildid: 20170221153143 08:03:15 INFO - mozversion application_changeset: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa 08:03:15 INFO - mozversion application_display_name: Nightly 08:03:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:03:15 INFO - mozversion application_name: Firefox 08:03:15 INFO - mozversion application_remotingname: firefox 08:03:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:03:15 INFO - mozversion application_vendor: Mozilla 08:03:15 INFO - mozversion application_version: 54.0a1 08:03:15 INFO - mozversion platform_buildid: 20170221153143 08:03:15 INFO - mozversion platform_changeset: 74cef34e1927a0c998ecb2fbe7836fd5cdcd43fa 08:03:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:03:15 INFO - mozversion platform_version: 54.0a1 08:03:15 INFO - using testdate: 1487692995 08:03:15 INFO - actual date: 1487692995 08:03:15 INFO - starting webserver on 'localhost:49192' 08:03:15 INFO - SUITE-START | Running 2 tests 08:03:15 INFO - TEST-START | dromaeo_css 08:03:15 INFO - operating with platform_type : linux_ 08:03:15 INFO - Initialising browser for dromaeo_css test... 08:03:15 INFO - TEST-INFO | started process 5104 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjfUGJR/profile http://localhost:49192/getInfo.html) 08:03:18 INFO - PROCESS | 5104 | Iterating over profile /tmp/tmpjfUGJR/profile 08:03:18 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:18 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/safebrowsing = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:18 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:18 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/mimeTypes.rdf = Tue Feb 21 2017 08:03:18 GMT-0800 (PST) 08:03:18 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:18 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/places.sqlite-wal = Tue Feb 21 2017 08:03:18 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/crashes = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/localstore.rdf = Tue Feb 21 2017 08:02:34 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | __metrics Screen width/height:1600/1200 08:03:19 INFO - PROCESS | 5104 | colorDepth:24 08:03:19 INFO - PROCESS | 5104 | Browser inner width/height: 1024/697 08:03:19 INFO - PROCESS | 5104 | __metrics 08:03:19 INFO - PROCESS | 5104 | Stat failure 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/permissions.sqlite = Tue Feb 21 2017 08:03:16 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/gmp = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/bookmarkbackups = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/blocklist.xml = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/extensions = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/storage.sqlite = Tue Feb 21 2017 08:03:18 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/places.sqlite = Tue Feb 21 2017 08:03:18 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/frequencyCap.json = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/minidumps = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/sessionCheckpoints.json = Tue Feb 21 2017 08:03:18 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/pluginreg.dat = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/compatibility.ini = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/webappsstore.sqlite = Tue Feb 21 2017 08:03:18 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/places.sqlite-shm = Tue Feb 21 2017 08:03:16 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | Stat failure 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/content-prefs.sqlite = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/extensions.json = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/extensions.ini = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | Stat failure 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/cache2 = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/cert8.db = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/thumbnails = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/.parentlock = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/directoryLinks.json = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/startupCache = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/secmod.db = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/prefs.js = Tue Feb 21 2017 08:02:34 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/key3.db = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/addons.json = Tue Feb 21 2017 08:03:17 GMT-0800 (PST) 08:03:19 INFO - PROCESS | 5104 | No birth date. Using mtime. 08:03:19 INFO - PROCESS | 5104 | Using date: /tmp/tmpjfUGJR/profile/user.js = Tue Feb 21 2017 08:03:15 GMT-0800 (PST) 08:03:20 ERROR - PROCESS | 5104 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 08:03:21 INFO - TEST-INFO | 5104: exit 0 08:03:21 INFO - Browser initialized. 08:03:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:03:21 INFO - TEST-INFO | started process 5236 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjfUGJR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:33 INFO - PROCESS | 5236 | RSS: Main: 157986816 08:03:33 INFO - PROCESS | 5236 | 08:06:37 INFO - PROCESS | 5236 | Cycle 1(1): loaded http://localhost:49192/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49192/tests/dromaeo/cssquery-ext.html) 08:06:39 INFO - PROCESS | 5236 | RSS: Main: 162185216 08:06:39 INFO - PROCESS | 5236 | 08:08:21 INFO - PROCESS | 5236 | ************************* 08:08:21 INFO - PROCESS | 5236 | A coding exception was thrown and uncaught in a Task. 08:08:21 INFO - PROCESS | 5236 | 08:08:21 ERROR - PROCESS | 5236 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:08:21 INFO - PROCESS | 5236 | Full stack: 08:08:21 INFO - PROCESS | 5236 | ************************* 08:09:41 INFO - PROCESS | 5236 | Cycle 1(1): loaded http://localhost:49192/tests/dromaeo/cssquery-ext.html (next: http://localhost:49192/tests/dromaeo/cssquery-jquery.html) 08:09:42 INFO - PROCESS | 5236 | RSS: Main: 157818880 08:09:42 INFO - PROCESS | 5236 | 08:12:44 INFO - PROCESS | 5236 | Cycle 1(1): loaded http://localhost:49192/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49192/tests/dromaeo/cssquery-mootools.html) 08:12:45 INFO - PROCESS | 5236 | RSS: Main: 165146624 08:12:45 INFO - PROCESS | 5236 | 08:15:47 INFO - PROCESS | 5236 | Cycle 1(1): loaded http://localhost:49192/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49192/tests/dromaeo/cssquery-prototype.html) 08:15:47 INFO - PROCESS | 5236 | RSS: Main: 157528064 08:15:47 INFO - PROCESS | 5236 | 08:18:50 INFO - PROCESS | 5236 | Cycle 1(1): loaded http://localhost:49192/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49192/tests/dromaeo/cssquery-yui.html) 08:18:51 INFO - PROCESS | 5236 | RSS: Main: 165212160 08:18:51 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | Cycle 1(1): loaded http://localhost:49192/tests/dromaeo/cssquery-yui.html (next: http://localhost:49192/tests/dromaeo/cssquery-dojo.html) 08:21:54 INFO - PROCESS | 5236 | RSS: Main: 158425088 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | __start_tp_report 08:21:54 INFO - PROCESS | 5236 | _x_x_mozilla_page_load 08:21:54 INFO - PROCESS | 5236 | _x_x_mozilla_page_load_details 08:21:54 INFO - PROCESS | 5236 | |i|pagename|runs| 08:21:54 INFO - PROCESS | 5236 | |0;dojo.html;2529.6079107738306;2537.416394229327;2546.5670835957885;2558.769710726034;2584.121398724434;4529.796159172837;4760.42874855017;4769.642276829239;4790.928136077961;4802.567768900803;4569.908601827961;4732.479427262998;4737.620990320775;4775.474697783241;4836.371271734678;4355.782210889459;4405.964598319392;4421.977890110545;4444.066745983331;4507.076049409863;4484.506704262545;4498.932516012263;4502.122086193193;4502.459704835424;4519.2995085761595;4281.828726850922;4292.0772034012525;4294.184105020036;4399.12017596482;4423.358613001097;4323.589259020388;4332.436783218193;4333.739975601443;4370;4376.0591472833175;4245.61789438952;4305.698601097924;4310.82756689732;4326.4159338489535;4329.913401731979;4581.152486789922;4610.2854057621125;4655.930161047587;4707.552782485658;4741.928871066937;5095.210242412432;5113.207452844815;5121.206213036985;5139.306193663882;5154.433012368674;5124.769385377667;5146.974265128688;5162.97418512905;5166.328377310925;5209.505097015777;4633.698809577434;4650.720956742539;4721.291806229025;4766.571008609242;4813.687110337852;5234.345706786652;5236.6857988520815;5241.947580524222;5254.054270231319;5293.523582877559;4820.903581928412;4869.464358920516;4939.580135688507;4959.652824302337;4962.181240095416;5081.618878584121;5098.311727916684;5105.540501354896;5105.770240339194;5132;4796.136695394863;4837.637177211724;4995.725235112031;5006.574441172541;5102.744862756847;4998.875028124326;5049.368828896388;5050.696958182521;5092.414372347227;5102.642815002914;4320.4599425071865;4323.135372925428;4325.697201195949;4353.34699795027;4355.7822108894425;3671.6695497405362;3681.815909204529;3704.81475926208;3741.064733816546;3759.7556158850134;4442.48911375196;4443.37792709021;4470.418845550123;4470.5976462117915;4479.059397526491;5144.6398752087;5147.665401748949;5153.510416510426;5158.871028224249;5199.350081239845;4292.18448494785;4448.421705178306;4449.221386257392;4461.955380446219;4504.166729155117;4491.1915855145735;4496.820127194941;4516.661250406232;4530.207213737649;4595.494495605481;4690.484046754786;4694.812207511662;4696.084263568708;4702.341672165968;4748.382710247645;5039.118154323052;5050.04049230645;5057.7724002419245;5059.544640982329;5093.312402825719;4557.225271703752;4586.1057093866375;4749.620030397629;4777.928331074967;4782.904341913212;5056.266841307916;5072.492750724898;5081.7713202905215;5099.87250318745;5100.234964755168;4871.610271178368;4917.28699338601;5041.218611115283;5104.617153713412;5185.170372740344;4944.357233559758;4945.060438516671;4951.554360107608;4963.975180124076;5023.8492845214705;4765.642576806684;4832.734199619054;4898.191798353374;4922.360093187863;4962.032403681392;4892.185030172217;4904.7302398367;4909.018196360814;4909.779059942382;4937.975310123426;4930.211166213388;4931.161702510653;4953.578945789648;4962.528559786814;5009.924851127309;4726.291056341439;4764.809407623657;4858.975705121452;4889.462159162418;4896.804127834846 08:21:54 INFO - PROCESS | 5236 | |1;ext.html;538.7494814911068;549.0830313376661;550.2351731093778;555.9908765590451;560.8457674139612;37415.99999999996;38504.99999999996;38692.41961370578;42725;42820.57179428205;39934.60065399345;40256.194876102534;40307.35697002422;40396;40427.39358909619;7535.9623201883915;7717.575533345664;7719.421043421766;9333.859992100124;9663;9422.916364618088;9479.95260023699;9509;9515.666951656693;9526.095020972996;9459.527023648825;9480.09939055792;9481.33630645855;9519.619215231382;9546.427214367126;9422.670206542773;9436.292278079103;9480.715578532656;9551.952240238823;9556.856647150298;9350.111739384816;9354.204892584139;9420.811583768285;9438.292128090421;9445.386049906734;33601.6639833601;33828;33876.49185262223;33895.15262118442;33927;3303.504474328846;3384.91104330944;3398.4562470004926;3459.066052165926;3461.359648465042;25690.229293121236;25691;25779.61330580024;25846.741532584623;26304.342391440176;3417.3507033663773;3535.5580552430947;3537.327907697555;3551.5205447264807;3659.9634003660153;225044;225449;226400;226708;226834;39498.40752388659;40420.393694094615;42532;42681.35977960333;45541.77229113833;46185.38146185422;48829.755851220514;49543.50456495389;49974.500254997714;50118.9976200474;12196.268223906594;12323;12368.75262494745;12370.319632420124;12380.81428778569;8890.688825891062;8891.066438023914;8898.955505222433;8954.86567701485;8979.685711000084;15665.023645507317;15752.842471575366;15771.68456630861;15778.526644200692;15812.841871581137;6419.582727122722;6511.15355003856;6566.507511936624;6686.130802995676;6688.197416310074;786.4691333349966;801.2228138705563;801.7073768074611;802.5987006496752;810.643316940556;26105.869470652524;26914.5962810558;27456.90172393122;27815.443691126064;28032.299192519942;941.8398872191743;952.1097773581723;954.055485069776;955.5890966884167;1061.9893801062044;13692.931535342459;14240.077589525488;14382.35279412429;14603.488877889224;14635.414583416758;315338;316766;316773;316880.662386751;319838;48065.27902081471;48547.271790923165;48666.75666621715;48740.51259487431;48748.5125148751;1179.8643156036994;1205.1202622085927;1211.8121691137712;1222.0345926717794;1225.577175874322;23210.419739506648;24310.270691879272;25050.74949250557;25264.621030684186;27234.72765272322;40609.79695101506;41091.383629244985;41125.79437102795;41176.17647646971;41181.382279266436;295835;299595;299787;299871;299934;22877.428064298525;23562.764372356058;23650.29049128529;23922.162724304566;24678.629820553066;36743.26513469662;36801;36993.44509832408;37257.62742372542;37884;31156.688433116284;31321.530177046905;31413.52879706852;31618.367632647678;32028.03915882527;30449;31627.841860790548;31853.522197166596;31864.36271274515;32109.03672889817;29756.55365169481;30099.548506772855;30207.395852082394;30514.542281866234;30588.388232234785;26420.735792642598;26953.460930780882;28357;28401.715982839905;28410.289742756595;21819.781802181777;21899.233526826483;22165.778342217018;22197.22309719152;22331.44171395728 08:21:54 INFO - PROCESS | 5236 | |2;jquery.html;381.79955523350253;393.4020289160475;394.899300678327;401.29172011399874;420.35895259728926;16468.58828529286;17261.741073883903;17833.732494012602;17967.371142010034;18080.095995200238;11250.099992000642;11276.774464510705;11517.539298428073;11586.710332241677;11625.128115391337;4772.2125849234835;4916.336294600237;4935.679180853233;4960.950390496085;4967.354243948282;4750.8597936495335;4806.110869489138;4874.536918992708;4906.9263961040615;4923.163062279403;4901.460839307673;4912.36139301892;4925.901481970359;4928.186849169901;4931.778069986859;4764.261539461354;4857.208224328808;4883.389576302962;4890.193118135503;4914.901701965959;4802.975985120087;4803;4809.5430934061205;4812.230043193107;4821.445533763627;12905.67735806603;12979.41592628334;13036.608901732963;13080.86919130806;13274.46902123914;2377.7265614454386;2456.312232574882;2501.2371226775826;2501.7998560115147;2511.7864981476414;9145.314101442418;9544.141027307576;9703;10059.044390782863;10064.597416103346;1757.2707326459504;1775.316503146298;1797.4158398520403;1799.2802878848433;1801.2974939773499;75220.62389688016;75288;76246.61876690581;76327.61836190784;77000.61499692466;10257.692269231786;10417.562204472506;10652.893471065345;10752.086072683753;10974.945125274322;15006.249687515581;15016.549503514912;15067.924660376628;15077.246137693071;15101.622459438602;6505.60966342021;6675.297799061208;6859.108315918998;6972.2330543640155;6972.755953541602;6106.603070800384;6116.724747386378;6256.155419018374;6307.274663413767;6311.779087731908;4552.453705555355;4611.584957353854;4657.767111644404;4670.556297151833;4672.556107169881;1948.6687263165013;1956.5010922215029;1977.0656393776283;2034.104993802722;2051.579426217622;803.935685145187;820.8645573480428;821.9219174178444;850.889384380039;861.7587075618836;16636;16952;17036.574085647957;17263;17793;809.2069771623842;809.2595275323014;819.2626636027622;820.8399362124328;842.8482873082904;8984;9341.953290233641;9542.52287385628;9712.514374281258;9789.314747967715;464986;466376;467007;469111;469993;85619;85663.57168214244;86040.56979715062;86879.56560217158;86930.13069869347;938.1837801113076;938.4556956964944;974.6539978307599;975.8194733974237;977.4282045003611;14535.982481226212;14639.926800366356;14922.626934326729;15269.160196189316;15627.296771645077;34987.47518787169;35158.82420587881;36623.45064823976;40352.596474034886;40581.18837623172;50725.74637126791;51965;52723.47276527339;53292.200616991555;54379.45620543744;7597.392208623407;7686.192949740421;7789.844203116019;7794.532328060334;8290.709825156091;9854.852177217492;11298.49156787963;11326.603568875049;11493.770124597628;12210;9324.160825525736;9362.859557106513;9693.854592181264;9696.75758106053;9845.753856153653;9824.45965471906;9831.606735730491;10020.348677336242;10023.797144342725;10121.139703125316;9352.625894964125;9442.10300021497;9569.425834449956;9854.802903942025;9908.653197138066;6681.365270299314;6865.691043903136;7059.399951004222;7204.207537171012;8411;9094;10365.844512332473;10393.116585090049;10546.947265263625;10916.454177291262 08:21:54 INFO - PROCESS | 5236 | |3;mootools.html;1307.4574051768516;1319.8350206224222;1325.9337033148347;1327.8713093870213;1335.6594068512532;7118.430525557949;7297.124345078598;7487.513311634753;7568.129665088516;7589.051368578921;7595.924040759591;7661.348785353238;7706.036745406825;7713;7717;2045.7136000959877;2101.8843963582;2102.106604693007;2105.694674272237;2110.282503948657;2050.4258807533915;2090.571432856261;2104.042660589428;2109.0930899713117;2115.4394085567337;2038.3884834549576;2057.855950083495;2067.2454554087726;2081.9583608327825;2083.468715477551;2067.080149333547;2082.208760670935;2085.979140208601;2086.426232785981;2087.488565301496;2009.7186393904865;2038.2865996901114;2088.3212955789277;2102.852800303979;2140.464883779055;7613.733519326797;7667.846643067163;7678;7692.076950765888;7704.65329060188;4508.098380323949;4625;4646.86059418218;4650.511696271877;4652.953470465286;2708.702042775293;2855;2890.6675732290637;2936.4127174565174;2943.882244710209;1060.8620879285643;1123.152020224725;1145.2555838704825;1145.2670291013758;1151.4185336405146;8675.349348798865;9070.727878163732;9955.352902061342;10017.949910250474;10059.245556583212;3903.921921561553;4042.191561687674;4075.2664520386616;4103;4110.0752330725345;8032.357411407073;8058.476199047161;8058.597070146469;8070.1526339734655;8071.152528984489;4537;4567.885802854888;4570.360149579062;4570.7943142558;4575.9771201143785;4091.8363265469047;4100.507939047334;4188.539260681323;4191.559886211965;4210.179015092089;3169.176014236269;3217.8069315841126;3256.4626836572174;3294.0447270291825;3305.8347082645773;969.3602222533094;986.0730912941812;992.8163289791412;997.5361456922566;1000.5347513406301;738.1695592458484;751.9285667861544;762.6529928882345;764.1746913333586;782.1083964280724;4687.10944920464;4763.833265835679;4818.10864989978;5069.645124841226;5080.364954380702;849.2356878808998;902.6615019367737;908.327837400319;910.2990697163148;918.6555041859302;3201.6638252983566;3343.481760327153;3367.478040903664;3378.273671160712;3526.5239192708655;9062.003179650234;9103.226225770752;9149.359544831797;9152.63389464414;9201.30990175726;8901.73294801157;9078.90921090794;9085.091490850862;9113.954430227806;9115.772105697411;6626.337366263431;6644.900326495106;6832.795016149524;6925.272846351161;6942.028116063856;4895.975520122377;5054.772535235997;5103.209002604603;5208.374995000473;5455.618106732491;7870.305668137514;8034.959825200836;8093.219491711814;8280.751577452686;8286.958565207135;7250.564966102051;7976.521408715495;8378.371622128243;8385.790355241168;8386.161383861565;6616.437602803623;6791.45668346541;6913.308669133046;6929;6945.756898508528;6850.280720524273;6957.547759395623;7024.157101147691;7114.6442677866935;7125.216226215216;6502.869942601216;6531.542792004514;6540.607563546202;6689.765858195135;6699.19609646826;6380.649064301508;6401.135846660642;6468.6442245676935;6507.642079685663;6568.27748947625;5709.314882214161;5802.448767367094;5812.912806307824;5818.534517238526;5821.650700957786;4479.148961697267;4501.752403617701;4506.211413002777;4558.8632341029825;4598.701084429501;4287.6998610097;4384.188925048876;4411.558844115562;4614.377059096979;4700.153972285021 08:21:54 INFO - PROCESS | 5236 | |4;prototype.html;352.5962772625344;362.2465272233754;366.75060958548187;374.77326217638347;378.6497489821914;5051.797928082877;5065;5099.209622508512;5100.693958362501;5106.183010718286;4970.105381031413;4996.600271978242;5013.846815232499;5037.1940489521685;5080.542751152396;3670.6696397324304;3863.497745293122;3872.186840763443;3906.6484016438517;3910.1788624388914;3866.845326186963;3892.3383024885698;3895.0067732728116;3902.2390633826417;3907.472491213678;3853.7880416577077;3863.497745293108;3866.671332936704;3876.3022655921923;3892.5718171001167;3632.164602141496;3853.7880416577077;3889.160912533852;3900.4539364488996;3905;3377.4596064629786;3378.864845406181;3389.9661003389897;3393.7454690898035;3411.880584179542;4893.87440888594;4903.362562866841;4917.434495033045;4920.434150072716;4927.610109097085;4445.555444455561;4454.5990860822685;4479.417675702138;4484.955150448486;4490.528494508062;5083.745812709387;5302.496262855023;5305.204219367087;5323.574114070904;5326.866828329284;3986.7807270600106;3992.960070399317;4010.6991975601654;4011.338129208677;4020;5301.893962120736;5426.810061647863;5581.8604534886545;5600.551955843523;5606.747696353633;3889.611038896088;3955.6835453163676;4007.6994225432627;4009.518857737032;4025.1144748155357;4066.776327302027;4072.8778136655947;4081.1635913040154;4103.563752686536;4139.958600414017;4528.366028755977;4604.30935359692;4611.8847028823875;4619.8845028873875;4640.907181856344;5325.349141766065;5328.8276579152525;5339.466053394707;5380.650257733235;5381.192821076792;1383.8546952570036;1396.9092009019585;1407.6973450708147;1409.8237720284965;1422.879055280292;966.950858318732;1012.250934308621;1017;1030.8866024737272;1031.2111234905303;4486.237339652267;4506.436695413074;4524.954750452519;4535.824922554225;4545.863444181229;5270.209468579667;5309.946900531023;5313.760880760375;5339.679619222859;5356.018273238927;4332.871755929808;4470.664700147502;4575.519570445122;4580.312953057068;4605.769711514478;2770.7644850187958;2810.6627204735564;2812.971870281312;2815.5354366529295;2818.8590570471392;4898.681585696918;4913.385826771653;4919.188333924933;4930.654854160174;4961.454240033593;5028.812466577051;5367.78528858849;5388.757505912244;5391.487808658171;5418.078926582491;4851.490593487633;4875.269279405831;4899.608031357483;4901.754912254373;4906.411230652345;5204.219367094966;5235.397929238186;5239.214117882349;5242.824227945513;5280.815171469134;4977.502249774993;5142.6914385137015;5194.805194805292;5201.869953251199;5218.191180367049;4804.735739534359;4869.269609558594;4891.777055736066;4897.155732749774;4930.161872481758;4043.353063509824;4091.629304183132;4097.037196258936;4111.48606424197;4118.691098167709;5432.945670543244;5454.372747133972;5456.181572763959;5460.6996615186545;5460.836174914759;5115.411727651368;5249.868753281045;5268.341457317835;5311.601629877698;5327.840164795062;5213.244079608359;5216.869578260574;5217.373915130057;5218.407801134977;5230.869228269324;5151.6908985461;5160.251763494347;5176.818811341585;5184.248283998874;5219.739013049409;5024.447310795883;5177.456367081553;5179;5203.346293390323;5290.1800220965815;5191.32512773337;5216.452812087665;5217.191335343028;5229.764660590206;5244.082285599929 08:21:54 INFO - PROCESS | 5236 | |5;yui.html;668.098067608728;673.807964730052;734.1483878700705;748.7080038784873;759.6847308367027;12502.249865008094;14048.438062477515;14074.648133796634;14079.577612671636;14091.365888535014;13029.934850325759;13104;13121.671958201026;13125.67185820355;13180.868191318084;753.9886901696451;797.1351084073787;806.1615919443801;808.5350923219144;812.2567850416891;824.7154731617583;824.7938015496126;825.352098602597;826.2150956591232;828.7306625346756;849.7918010087507;854.0605334132466;854.2226573817827;854.3208149521146;856.9100244474334;851.8551846186164;853.2193043365313;854.3250831842868;855.2943821347383;857.618359829876;622.6015350175891;623.5603899251017;624.213491001337;624.7282432142033;624.8188025472652;13658.590242292648;13908.374123164482;13938.163710177321;13946.442142314396;14000;12208.32854193019;12420.378981050912;12469.812952805714;12717.427715752814;12975.351232438436;10487.370757754483;10625.9468702656;10631.84052239217;10740.624078157305;10902.454877256185;3143.4813255812765;3152.6374466936136;3234.450143475615;3292;3301.735861131104;60264;60749.69625151846;61101;62085.068723969176;62085.37914620841;9704.320697551244;9891.208703303862;9900.7029789105;9919.553620087114;9960.601575937026;16846.07346595949;16972.15139243033;17044.744328835077;17202.913985430245;17328.566785830455;6061;6400.7119679614525;6414.294427612959;6528.347165283433;6577.80266592003;3410.795352278871;3550.0769799852224;3585.15748799032;3703.129764505339;3705.27747089315;2968.168912704446;3048.0551029181024;3070.5854709614364;3072.324088700482;3092.829894355787;502.3845788908558;503.0693217547508;510.2983397827987;513.6481510165548;523.9764210610532;595.7319206357157;615.2404124204771;618.5670030978243;631.4506379449908;631.5200447659812;8346.833063338821;8375.916240837636;8860.601272942733;8995.595198216097;9410.153086222273;638.8850006998784;641.7753786174801;666.6333516565869;667.6194569095569;668.2949488289901;4205.894852628709;4300.204462174508;4319.935200971988;4347.782898919258;4427.800748966304;48989.51010489921;50268.49731502638;62084.223984657525;64296;67244.3275567238;55438.72280638652;55534;56152.43847561554;56913;57539;48370.58147092702;48691.75654121778;49142.26286605704;49555.50444495509;50061.74969125131;7984.6406911687955;8336.87494687568;8343.499390036617;8366.958165209135;8527.403081784463;28505.28736781597;30405.69594304117;31795.523067154474;32758.50862237116;37355.252894942496;42310.153796923274;42791.57208428001;43093.35359969666;44549.55450445454;44690;11008.889911101107;11265.718357040812;11299.152563557602;11497.08023358146;11647.184697071463;12358.011359091432;12485.375731213584;12532.62402127901;12860.421281042563;13148.211107333591;11201.271917325348;11659.300441973508;11708.765824683629;12033.518659253532;12491.562795302121;11768.82346764782;11799.469023894117;12007.399630018639;12242;12398.62804115878;10932.523811180154;10934;11163;11321.886781132083;11471.541138354372;9944.801103978025;10004.499775011365;10012;10030.29787914839;10346.741331466774;8981.955090224506;9562.808743825224;9632.9036709632;9704.913478329647;9758.560864761244 08:21:54 INFO - PROCESS | 5236 | __end_tp_report 08:21:54 INFO - PROCESS | 5236 | __start_cc_report 08:21:54 INFO - PROCESS | 5236 | _x_x_mozilla_cycle_collect,383 08:21:54 INFO - PROCESS | 5236 | __end_cc_report 08:21:54 INFO - PROCESS | 5236 | __startTimestamp1487694114741__endTimestamp 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | ------- Summary: start ------- 08:21:54 INFO - PROCESS | 5236 | Number of tests: 6 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | [#0] dojo.html Cycles:165 Average:4684.67 Median:4777.93 stddev:506.80 (10.6%) stddev-sans-first:479.33 08:21:54 INFO - PROCESS | 5236 | Values: 2529.6 2537.4 2546.6 2558.8 2584.1 4529.8 4760.4 4769.6 4790.9 4802.6 4569.9 4732.5 4737.6 4775.5 4836.4 4355.8 4406.0 4422.0 4444.1 4507.1 4484.5 4498.9 4502.1 4502.5 4519.3 4281.8 4292.1 4294.2 4399.1 4423.4 4323.6 4332.4 4333.7 4370.0 4376.1 4245.6 4305.7 4310.8 4326.4 4329.9 4581.2 4610.3 4655.9 4707.6 4741.9 5095.2 5113.2 5121.2 5139.3 5154.4 5124.8 5147.0 5163.0 5166.3 5209.5 4633.7 4650.7 4721.3 4766.6 4813.7 5234.3 5236.7 5241.9 5254.1 5293.5 4820.9 4869.5 4939.6 4959.7 4962.2 5081.6 5098.3 5105.5 5105.8 5132.0 4796.1 4837.6 4995.7 5006.6 5102.7 4998.9 5049.4 5050.7 5092.4 5102.6 4320.5 4323.1 4325.7 4353.3 4355.8 3671.7 3681.8 3704.8 3741.1 3759.8 4442.5 4443.4 4470.4 4470.6 4479.1 5144.6 5147.7 5153.5 5158.9 5199.4 4292.2 4448.4 4449.2 4462.0 4504.2 4491.2 4496.8 4516.7 4530.2 4595.5 4690.5 4694.8 4696.1 4702.3 4748.4 5039.1 5050.0 5057.8 5059.5 5093.3 4557.2 4586.1 4749.6 4777.9 4782.9 5056.3 5072.5 5081.8 5099.9 5100.2 4871.6 4917.3 5041.2 5104.6 5185.2 4944.4 4945.1 4951.6 4964.0 5023.8 4765.6 4832.7 4898.2 4922.4 4962.0 4892.2 4904.7 4909.0 4909.8 4938.0 4930.2 4931.2 4953.6 4962.5 5009.9 4726.3 4764.8 4859.0 4889.5 4896.8 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | [#1] ext.html Cycles:180 Average:42604.39 Median:3460.21 stddev:74305.79 (2147.4%) stddev-sans-first:74447.11 08:21:54 INFO - PROCESS | 5236 | Values: 538.7 549.1 550.2 556.0 560.8 37416.0 38505.0 38692.4 42725.0 42820.6 39934.6 40256.2 40307.4 40396.0 40427.4 7536.0 7717.6 7719.4 9333.9 9663.0 9422.9 9480.0 9509.0 9515.7 9526.1 9459.5 9480.1 9481.3 9519.6 9546.4 9422.7 9436.3 9480.7 9552.0 9556.9 9350.1 9354.2 9420.8 9438.3 9445.4 33601.7 33828.0 33876.5 33895.2 33927.0 3303.5 3384.9 3398.5 3459.1 3461.4 25690.2 25691.0 25779.6 25846.7 26304.3 3417.4 3535.6 3537.3 3551.5 3660.0 225044.0 225449.0 226400.0 226708.0 226834.0 39498.4 40420.4 42532.0 42681.4 45541.8 46185.4 48829.8 49543.5 49974.5 50119.0 12196.3 12323.0 12368.8 12370.3 12380.8 8890.7 8891.1 8899.0 8954.9 8979.7 15665.0 15752.8 15771.7 15778.5 15812.8 6419.6 6511.2 6566.5 6686.1 6688.2 786.5 801.2 801.7 802.6 810.6 26105.9 26914.6 27456.9 27815.4 28032.3 941.8 952.1 954.1 955.6 1062.0 13692.9 14240.1 14382.4 14603.5 14635.4 315338.0 316766.0 316773.0 316880.7 319838.0 48065.3 48547.3 48666.8 48740.5 48748.5 1179.9 1205.1 1211.8 1222.0 1225.6 23210.4 24310.3 25050.7 25264.6 27234.7 40609.8 41091.4 41125.8 41176.2 41181.4 295835.0 299595.0 299787.0 299871.0 299934.0 22877.4 23562.8 23650.3 23922.2 24678.6 36743.3 36801.0 36993.4 37257.6 37884.0 31156.7 31321.5 31413.5 31618.4 32028.0 30449.0 31627.8 31853.5 31864.4 32109.0 29756.6 30099.5 30207.4 30514.5 30588.4 26420.7 26953.5 28357.0 28401.7 28410.3 21819.8 21899.2 22165.8 22197.2 22331.4 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | [#2] jquery.html Cycles:180 Average:26553.27 Median:4816.84 stddev:77151.51 (1601.7%) stddev-sans-first:77342.92 08:21:54 INFO - PROCESS | 5236 | Values: 381.8 393.4 394.9 401.3 420.4 16468.6 17261.7 17833.7 17967.4 18080.1 11250.1 11276.8 11517.5 11586.7 11625.1 4772.2 4916.3 4935.7 4961.0 4967.4 4750.9 4806.1 4874.5 4906.9 4923.2 4901.5 4912.4 4925.9 4928.2 4931.8 4764.3 4857.2 4883.4 4890.2 4914.9 4803.0 4803.0 4809.5 4812.2 4821.4 12905.7 12979.4 13036.6 13080.9 13274.5 2377.7 2456.3 2501.2 2501.8 2511.8 9145.3 9544.1 9703.0 10059.0 10064.6 1757.3 1775.3 1797.4 1799.3 1801.3 75220.6 75288.0 76246.6 76327.6 77000.6 10257.7 10417.6 10652.9 10752.1 10974.9 15006.2 15016.5 15067.9 15077.2 15101.6 6505.6 6675.3 6859.1 6972.2 6972.8 6106.6 6116.7 6256.2 6307.3 6311.8 4552.5 4611.6 4657.8 4670.6 4672.6 1948.7 1956.5 1977.1 2034.1 2051.6 803.9 820.9 821.9 850.9 861.8 16636.0 16952.0 17036.6 17263.0 17793.0 809.2 809.3 819.3 820.8 842.8 8984.0 9342.0 9542.5 9712.5 9789.3 464986.0 466376.0 467007.0 469111.0 469993.0 85619.0 85663.6 86040.6 86879.6 86930.1 938.2 938.5 974.7 975.8 977.4 14536.0 14639.9 14922.6 15269.2 15627.3 34987.5 35158.8 36623.5 40352.6 40581.2 50725.7 51965.0 52723.5 53292.2 54379.5 7597.4 7686.2 7789.8 7794.5 8290.7 9854.9 11298.5 11326.6 11493.8 12210.0 9324.2 9362.9 9693.9 9696.8 9845.8 9824.5 9831.6 10020.3 10023.8 10121.1 9352.6 9442.1 9569.4 9854.8 9908.7 6681.4 6865.7 7059.4 7204.2 8411.0 9094.0 10365.8 10393.1 10546.9 10916.5 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | [#3] mootools.html Cycles:180 Average:4879.49 Median:5155.79 stddev:2663.36 (51.7%) stddev-sans-first:2657.30 08:21:54 INFO - PROCESS | 5236 | Values: 1307.5 1319.8 1325.9 1327.9 1335.7 7118.4 7297.1 7487.5 7568.1 7589.1 7595.9 7661.3 7706.0 7713.0 7717.0 2045.7 2101.9 2102.1 2105.7 2110.3 2050.4 2090.6 2104.0 2109.1 2115.4 2038.4 2057.9 2067.2 2082.0 2083.5 2067.1 2082.2 2086.0 2086.4 2087.5 2009.7 2038.3 2088.3 2102.9 2140.5 7613.7 7667.8 7678.0 7692.1 7704.7 4508.1 4625.0 4646.9 4650.5 4653.0 2708.7 2855.0 2890.7 2936.4 2943.9 1060.9 1123.2 1145.3 1145.3 1151.4 8675.3 9070.7 9955.4 10017.9 10059.2 3903.9 4042.2 4075.3 4103.0 4110.1 8032.4 8058.5 8058.6 8070.2 8071.2 4537.0 4567.9 4570.4 4570.8 4576.0 4091.8 4100.5 4188.5 4191.6 4210.2 3169.2 3217.8 3256.5 3294.0 3305.8 969.4 986.1 992.8 997.5 1000.5 738.2 751.9 762.7 764.2 782.1 4687.1 4763.8 4818.1 5069.6 5080.4 849.2 902.7 908.3 910.3 918.7 3201.7 3343.5 3367.5 3378.3 3526.5 9062.0 9103.2 9149.4 9152.6 9201.3 8901.7 9078.9 9085.1 9114.0 9115.8 6626.3 6644.9 6832.8 6925.3 6942.0 4896.0 5054.8 5103.2 5208.4 5455.6 7870.3 8035.0 8093.2 8280.8 8287.0 7250.6 7976.5 8378.4 8385.8 8386.2 6616.4 6791.5 6913.3 6929.0 6945.8 6850.3 6957.5 7024.2 7114.6 7125.2 6502.9 6531.5 6540.6 6689.8 6699.2 6380.6 6401.1 6468.6 6507.6 6568.3 5709.3 5802.4 5812.9 5818.5 5821.7 4479.1 4501.8 4506.2 4558.9 4598.7 4287.7 4384.2 4411.6 4614.4 4700.2 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | [#4] prototype.html Cycles:180 Average:4362.50 Median:4897.92 stddev:1230.15 (25.1%) stddev-sans-first:1196.22 08:21:54 INFO - PROCESS | 5236 | Values: 352.6 362.2 366.8 374.8 378.6 5051.8 5065.0 5099.2 5100.7 5106.2 4970.1 4996.6 5013.8 5037.2 5080.5 3670.7 3863.5 3872.2 3906.6 3910.2 3866.8 3892.3 3895.0 3902.2 3907.5 3853.8 3863.5 3866.7 3876.3 3892.6 3632.2 3853.8 3889.2 3900.5 3905.0 3377.5 3378.9 3390.0 3393.7 3411.9 4893.9 4903.4 4917.4 4920.4 4927.6 4445.6 4454.6 4479.4 4485.0 4490.5 5083.7 5302.5 5305.2 5323.6 5326.9 3986.8 3993.0 4010.7 4011.3 4020.0 5301.9 5426.8 5581.9 5600.6 5606.7 3889.6 3955.7 4007.7 4009.5 4025.1 4066.8 4072.9 4081.2 4103.6 4140.0 4528.4 4604.3 4611.9 4619.9 4640.9 5325.3 5328.8 5339.5 5380.7 5381.2 1383.9 1396.9 1407.7 1409.8 1422.9 967.0 1012.3 1017.0 1030.9 1031.2 4486.2 4506.4 4525.0 4535.8 4545.9 5270.2 5309.9 5313.8 5339.7 5356.0 4332.9 4470.7 4575.5 4580.3 4605.8 2770.8 2810.7 2813.0 2815.5 2818.9 4898.7 4913.4 4919.2 4930.7 4961.5 5028.8 5367.8 5388.8 5391.5 5418.1 4851.5 4875.3 4899.6 4901.8 4906.4 5204.2 5235.4 5239.2 5242.8 5280.8 4977.5 5142.7 5194.8 5201.9 5218.2 4804.7 4869.3 4891.8 4897.2 4930.2 4043.4 4091.6 4097.0 4111.5 4118.7 5432.9 5454.4 5456.2 5460.7 5460.8 5115.4 5249.9 5268.3 5311.6 5327.8 5213.2 5216.9 5217.4 5218.4 5230.9 5151.7 5160.3 5176.8 5184.2 5219.7 5024.4 5177.5 5179.0 5203.3 5290.2 5191.3 5216.5 5217.2 5229.8 5244.1 08:21:54 INFO - PROCESS | 5236 | 08:21:54 INFO - PROCESS | 5236 | [#5] yui.html Cycles:180 Average:14297.47 Median:48840.63 stddev:17334.22 (35.5%) stddev-sans-first:17352.64 08:21:54 INFO - PROCESS | 5236 | Values: 668.1 673.8 734.1 748.7 759.7 12502.2 14048.4 14074.6 14079.6 14091.4 13029.9 13104.0 13121.7 13125.7 13180.9 754.0 797.1 806.2 808.5 812.3 824.7 824.8 825.4 826.2 828.7 849.8 854.1 854.2 854.3 856.9 851.9 853.2 854.3 855.3 857.6 622.6 623.6 624.2 624.7 624.8 13658.6 13908.4 13938.2 13946.4 14000.0 12208.3 12420.4 12469.8 12717.4 12975.4 10487.4 10625.9 10631.8 10740.6 10902.5 3143.5 3152.6 3234.5 3292.0 3301.7 60264.0 60749.7 61101.0 62085.1 62085.4 9704.3 9891.2 9900.7 9919.6 9960.6 16846.1 16972.2 17044.7 17202.9 17328.6 6061.0 6400.7 6414.3 6528.3 6577.8 3410.8 3550.1 3585.2 3703.1 3705.3 2968.2 3048.1 3070.6 3072.3 3092.8 502.4 503.1 510.3 513.6 524.0 595.7 615.2 618.6 631.5 631.5 8346.8 8375.9 8860.6 8995.6 9410.2 638.9 641.8 666.6 667.6 668.3 4205.9 4300.2 4319.9 4347.8 4427.8 48989.5 50268.5 62084.2 64296.0 67244.3 55438.7 55534.0 56152.4 56913.0 57539.0 48370.6 48691.8 49142.3 49555.5 50061.7 7984.6 8336.9 8343.5 8367.0 8527.4 28505.3 30405.7 31795.5 32758.5 37355.3 42310.2 42791.6 43093.4 44549.6 44690.0 11008.9 11265.7 11299.2 11497.1 11647.2 12358.0 12485.4 12532.6 12860.4 13148.2 11201.3 11659.3 11708.8 12033.5 12491.6 11768.8 11799.5 12007.4 12242.0 12398.6 10932.5 10934.0 11163.0 11321.9 11471.5 9944.8 10004.5 10012.0 10030.3 10346.7 8982.0 9562.8 9632.9 9704.9 9758.6 08:21:54 INFO - PROCESS | 5236 | -------- Summary: end -------- 08:21:54 INFO - PROCESS | 5236 | 08:21:55 INFO - TEST-INFO | 5236: exit 0 08:21:55 INFO - TEST-OK | dromaeo_css | took 1120486ms 08:21:55 INFO - TEST-START | kraken 08:21:55 INFO - operating with platform_type : linux_ 08:21:55 INFO - Initialising browser for kraken test... 08:21:55 INFO - TEST-INFO | started process 5618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU6H3YL/profile http://localhost:49192/getInfo.html) 08:21:58 INFO - PROCESS | 5618 | Iterating over profile /tmp/tmpU6H3YL/profile 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/safebrowsing = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/places.sqlite-wal = Tue Feb 21 2017 08:21:59 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/crashes = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/localstore.rdf = Tue Feb 21 2017 08:02:34 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/permissions.sqlite = Tue Feb 21 2017 08:21:56 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/gmp = Tue Feb 21 2017 08:21:56 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/bookmarkbackups = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/blocklist.xml = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/extensions = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/places.sqlite = Tue Feb 21 2017 08:21:59 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/frequencyCap.json = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/minidumps = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/sessionCheckpoints.json = Tue Feb 21 2017 08:21:58 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | __metrics Screen width/height:1600/1200 08:21:59 INFO - PROCESS | 5618 | colorDepth:24 08:21:59 INFO - PROCESS | 5618 | Browser inner width/height: 1024/697 08:21:59 INFO - PROCESS | 5618 | __metrics 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/pluginreg.dat = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/compatibility.ini = Tue Feb 21 2017 08:21:56 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/places.sqlite-shm = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | Stat failure 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/content-prefs.sqlite = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/extensions.json = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/extensions.ini = Tue Feb 21 2017 08:21:56 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/cache2 = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/cert8.db = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/thumbnails = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/.parentlock = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/directoryLinks.json = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/startupCache = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/secmod.db = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/prefs.js = Tue Feb 21 2017 08:02:34 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/key3.db = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/addons.json = Tue Feb 21 2017 08:21:57 GMT-0800 (PST) 08:21:59 INFO - PROCESS | 5618 | No birth date. Using mtime. 08:21:59 INFO - PROCESS | 5618 | Using date: /tmp/tmpU6H3YL/profile/user.js = Tue Feb 21 2017 08:21:55 GMT-0800 (PST) 08:22:00 ERROR - PROCESS | 5618 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 08:22:01 INFO - TEST-INFO | 5618: exit 0 08:22:01 INFO - Browser initialized. 08:22:01 INFO - Running cycle 1/1 for kraken test... 08:22:01 INFO - TEST-INFO | started process 5747 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU6H3YL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:13 INFO - PROCESS | 5747 | RSS: Main: 153419776 08:22:13 INFO - PROCESS | 5747 | 08:22:16 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49192/tests/kraken/driver.html?testName=audio-beat-detection) 08:22:17 INFO - PROCESS | 5747 | RSS: Main: 160391168 08:22:17 INFO - PROCESS | 5747 | 08:22:19 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49192/tests/kraken/driver.html?testName=audio-dft) 08:22:20 INFO - PROCESS | 5747 | RSS: Main: 159518720 08:22:20 INFO - PROCESS | 5747 | 08:22:23 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49192/tests/kraken/driver.html?testName=audio-fft) 08:22:24 INFO - PROCESS | 5747 | RSS: Main: 155328512 08:22:24 INFO - PROCESS | 5747 | 08:22:26 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49192/tests/kraken/driver.html?testName=audio-oscillator) 08:22:26 INFO - PROCESS | 5747 | RSS: Main: 155648000 08:22:26 INFO - PROCESS | 5747 | 08:22:29 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49192/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:22:29 INFO - PROCESS | 5747 | RSS: Main: 156319744 08:22:29 INFO - PROCESS | 5747 | 08:22:32 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49192/tests/kraken/driver.html?testName=imaging-darkroom) 08:22:33 INFO - PROCESS | 5747 | RSS: Main: 161198080 08:22:33 INFO - PROCESS | 5747 | 08:22:36 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49192/tests/kraken/driver.html?testName=imaging-desaturate) 08:22:37 INFO - PROCESS | 5747 | RSS: Main: 158011392 08:22:37 INFO - PROCESS | 5747 | 08:22:40 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49192/tests/kraken/driver.html?testName=json-parse-financial) 08:22:41 INFO - PROCESS | 5747 | RSS: Main: 158875648 08:22:41 INFO - PROCESS | 5747 | 08:22:42 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49192/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:22:43 INFO - PROCESS | 5747 | RSS: Main: 159244288 08:22:43 INFO - PROCESS | 5747 | 08:22:45 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:22:46 INFO - PROCESS | 5747 | RSS: Main: 159272960 08:22:46 INFO - PROCESS | 5747 | 08:22:48 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:22:49 INFO - PROCESS | 5747 | RSS: Main: 159948800 08:22:49 INFO - PROCESS | 5747 | 08:22:51 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:22:52 INFO - PROCESS | 5747 | RSS: Main: 160468992 08:22:52 INFO - PROCESS | 5747 | 08:22:55 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:22:56 INFO - PROCESS | 5747 | RSS: Main: 160071680 08:22:56 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | Cycle 1(1): loaded http://localhost:49192/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49192/tests/kraken/driver.html?testName=ai-astar) 08:22:58 INFO - PROCESS | 5747 | RSS: Main: 161787904 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | __start_tp_report 08:22:58 INFO - PROCESS | 5747 | _x_x_mozilla_page_load 08:22:58 INFO - PROCESS | 5747 | _x_x_mozilla_page_load_details 08:22:58 INFO - PROCESS | 5747 | |i|pagename|runs| 08:22:58 INFO - PROCESS | 5747 | |0;ai-astar;108;116;123;115;114;115;115;114;115;116 08:22:58 INFO - PROCESS | 5747 | |1;audio-beat-detection;153;134;131;182;128;130;127;127;159;128 08:22:58 INFO - PROCESS | 5747 | |2;audio-dft;164;166;166;168;174;163;164;162;169;169 08:22:58 INFO - PROCESS | 5747 | |3;audio-fft;77;77;77;103;79;81;82;78;97;79 08:22:58 INFO - PROCESS | 5747 | |4;audio-oscillator;86;86;102;85;86;84;97;86;85;107 08:22:58 INFO - PROCESS | 5747 | |5;imaging-gaussian-blur;114;115;113;114;114;119;114;116;115;114 08:22:58 INFO - PROCESS | 5747 | |6;imaging-darkroom;151;151;151;151;150;151;150;152;150;151 08:22:58 INFO - PROCESS | 5747 | |7;imaging-desaturate;97;96;95;96;96;97;97;95;102;100 08:22:58 INFO - PROCESS | 5747 | |8;json-parse-financial;67;67;66;70;70;67;68;68;68;67 08:22:58 INFO - PROCESS | 5747 | |9;json-stringify-tinderbox;67;66;66;68;69;65;66;66;66;67 08:22:58 INFO - PROCESS | 5747 | |10;stanford-crypto-aes;96;92;91;92;93;90;91;89;89;90 08:22:58 INFO - PROCESS | 5747 | |11;stanford-crypto-ccm;153;140;141;137;136;144;141;145;189;150 08:22:58 INFO - PROCESS | 5747 | |12;stanford-crypto-pbkdf2;183;184;191;192;186;187;189;188;191;187 08:22:58 INFO - PROCESS | 5747 | |13;stanford-crypto-sha256-iterative;72;71;70;71;73;75;74;75;73;69 08:22:58 INFO - PROCESS | 5747 | __end_tp_report 08:22:58 INFO - PROCESS | 5747 | __start_cc_report 08:22:58 INFO - PROCESS | 5747 | _x_x_mozilla_cycle_collect,875 08:22:58 INFO - PROCESS | 5747 | __end_cc_report 08:22:58 INFO - PROCESS | 5747 | __startTimestamp1487694178502__endTimestamp 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | ------- Summary: start ------- 08:22:58 INFO - PROCESS | 5747 | Number of tests: 14 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.00 stddev:3.60 (3.1%) stddev-sans-first:2.76 08:22:58 INFO - PROCESS | 5747 | Values: 108.0 116.0 123.0 115.0 114.0 115.0 115.0 114.0 115.0 116.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:132.50 stddev:18.67 (14.1%) stddev-sans-first:19.19 08:22:58 INFO - PROCESS | 5747 | Values: 153.0 134.0 131.0 182.0 128.0 130.0 127.0 127.0 159.0 128.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#2] audio-dft Cycles:10 Average:166.50 Median:167.00 stddev:3.60 (2.2%) stddev-sans-first:3.70 08:22:58 INFO - PROCESS | 5747 | Values: 164.0 166.0 166.0 168.0 174.0 163.0 164.0 162.0 169.0 169.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#3] audio-fft Cycles:10 Average:83.00 Median:79.00 stddev:9.23 (11.7%) stddev-sans-first:9.53 08:22:58 INFO - PROCESS | 5747 | Values: 77.0 77.0 77.0 103.0 79.0 81.0 82.0 78.0 97.0 79.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:86.00 stddev:8.37 (9.7%) stddev-sans-first:8.72 08:22:58 INFO - PROCESS | 5747 | Values: 86.0 86.0 102.0 85.0 86.0 84.0 97.0 86.0 85.0 107.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#5] imaging-gaussian-blur Cycles:10 Average:114.80 Median:114.50 stddev:1.69 (1.5%) stddev-sans-first:1.76 08:22:58 INFO - PROCESS | 5747 | Values: 114.0 115.0 113.0 114.0 114.0 119.0 114.0 116.0 115.0 114.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 08:22:58 INFO - PROCESS | 5747 | Values: 151.0 151.0 151.0 151.0 150.0 151.0 150.0 152.0 150.0 151.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:96.00 stddev:2.23 (2.3%) stddev-sans-first:2.37 08:22:58 INFO - PROCESS | 5747 | Values: 97.0 96.0 95.0 96.0 96.0 97.0 97.0 95.0 102.0 100.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:68.00 stddev:1.32 (1.9%) stddev-sans-first:1.36 08:22:58 INFO - PROCESS | 5747 | Values: 67.0 67.0 66.0 70.0 70.0 67.0 68.0 68.0 68.0 67.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:66.50 stddev:1.17 (1.8%) stddev-sans-first:1.24 08:22:58 INFO - PROCESS | 5747 | Values: 67.0 66.0 66.0 68.0 69.0 65.0 66.0 66.0 66.0 67.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:91.50 stddev:2.11 (2.3%) stddev-sans-first:1.39 08:22:58 INFO - PROCESS | 5747 | Values: 96.0 92.0 91.0 92.0 93.0 90.0 91.0 89.0 89.0 90.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:144.50 stddev:15.49 (10.7%) stddev-sans-first:16.31 08:22:58 INFO - PROCESS | 5747 | Values: 153.0 140.0 141.0 137.0 136.0 144.0 141.0 145.0 189.0 150.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:188.50 stddev:3.01 (1.6%) stddev-sans-first:2.65 08:22:58 INFO - PROCESS | 5747 | Values: 183.0 184.0 191.0 192.0 186.0 187.0 189.0 188.0 191.0 187.0 08:22:58 INFO - PROCESS | 5747 | 08:22:58 INFO - PROCESS | 5747 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:73.00 stddev:2.06 (2.8%) stddev-sans-first:2.18 08:22:58 INFO - PROCESS | 5747 | Values: 72.0 71.0 70.0 71.0 73.0 75.0 74.0 75.0 73.0 69.0 08:22:58 INFO - PROCESS | 5747 | -------- Summary: end -------- 08:22:58 INFO - PROCESS | 5747 | 08:22:59 INFO - TEST-INFO | 5747: exit 0 08:22:59 INFO - TEST-OK | kraken | took 64029ms 08:22:59 INFO - SUITE-END | took 1184s 08:23:00 INFO - Completed test suite (00:19:45) 08:23:00 INFO - javascript benchmark 08:23:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6294.297412139645, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2529.6079107738306, 2537.416394229327, 2546.5670835957885, 2558.769710726034, 2584.121398724434, 4529.796159172837, 4760.42874855017, 4769.642276829239, 4790.928136077961, 4802.567768900803, 4569.908601827961, 4732.479427262998, 4737.620990320775, 4775.474697783241, 4836.371271734678, 4355.782210889459, 4405.964598319392, 4421.977890110545, 4444.066745983331, 4507.076049409863, 4484.506704262545, 4498.932516012263, 4502.122086193193, 4502.459704835424, 4519.2995085761595, 4281.828726850922, 4292.0772034012525, 4294.184105020036, 4399.12017596482, 4423.358613001097, 4323.589259020388, 4332.436783218193, 4333.739975601443, 4370.0, 4376.0591472833175, 4245.61789438952, 4305.698601097924, 4310.82756689732, 4326.4159338489535, 4329.913401731979, 4581.152486789922, 4610.2854057621125, 4655.930161047587, 4707.552782485658, 4741.928871066937, 5095.210242412432, 5113.207452844815, 5121.206213036985, 5139.306193663882, 5154.433012368674, 5124.769385377667, 5146.974265128688, 5162.97418512905, 5166.328377310925, 5209.505097015777, 4633.698809577434, 4650.720956742539, 4721.291806229025, 4766.571008609242, 4813.687110337852, 5234.345706786652, 5236.6857988520815, 5241.947580524222, 5254.054270231319, 5293.523582877559, 4820.903581928412, 4869.464358920516, 4939.580135688507, 4959.652824302337, 4962.181240095416, 5081.618878584121, 5098.311727916684, 5105.540501354896, 5105.770240339194, 5132.0, 4796.136695394863, 4837.637177211724, 4995.725235112031, 5006.574441172541, 5102.744862756847, 4998.875028124326, 5049.368828896388, 5050.696958182521, 5092.414372347227, 5102.642815002914, 4320.4599425071865, 4323.135372925428, 4325.697201195949, 4353.34699795027, 4355.7822108894425, 3671.6695497405362, 3681.815909204529, 3704.81475926208, 3741.064733816546, 3759.7556158850134, 4442.48911375196, 4443.37792709021, 4470.418845550123, 4470.5976462117915, 4479.059397526491, 5144.6398752087, 5147.665401748949, 5153.510416510426, 5158.871028224249, 5199.350081239845, 4292.18448494785, 4448.421705178306, 4449.221386257392, 4461.955380446219, 4504.166729155117, 4491.1915855145735, 4496.820127194941, 4516.661250406232, 4530.207213737649, 4595.494495605481, 4690.484046754786, 4694.812207511662, 4696.084263568708, 4702.341672165968, 4748.382710247645, 5039.118154323052, 5050.04049230645, 5057.7724002419245, 5059.544640982329, 5093.312402825719, 4557.225271703752, 4586.1057093866375, 4749.620030397629, 4777.928331074967, 4782.904341913212, 5056.266841307916, 5072.492750724898, 5081.7713202905215, 5099.87250318745, 5100.234964755168, 4871.610271178368, 4917.28699338601, 5041.218611115283, 5104.617153713412, 5185.170372740344, 4944.357233559758, 4945.060438516671, 4951.554360107608, 4963.975180124076, 5023.8492845214705, 4765.642576806684, 4832.734199619054, 4898.191798353374, 4922.360093187863, 4962.032403681392, 4892.185030172217, 4904.7302398367, 4909.018196360814, 4909.779059942382, 4937.975310123426, 4930.211166213388, 4931.161702510653, 4953.578945789648, 4962.528559786814, 5009.924851127309, 4726.291056341439, 4764.809407623657, 4858.975705121452, 4889.462159162418, 4896.804127834846], "value": 4650.7297596610215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.7494814911068, 549.0830313376661, 550.2351731093778, 555.9908765590451, 560.8457674139612, 37415.99999999996, 38504.99999999996, 38692.41961370578, 42725.0, 42820.57179428205, 39934.60065399345, 40256.194876102534, 40307.35697002422, 40396.0, 40427.39358909619, 7535.9623201883915, 7717.575533345664, 7719.421043421766, 9333.859992100124, 9663.0, 9422.916364618088, 9479.95260023699, 9509.0, 9515.666951656693, 9526.095020972996, 9459.527023648825, 9480.09939055792, 9481.33630645855, 9519.619215231382, 9546.427214367126, 9422.670206542773, 9436.292278079103, 9480.715578532656, 9551.952240238823, 9556.856647150298, 9350.111739384816, 9354.204892584139, 9420.811583768285, 9438.292128090421, 9445.386049906734, 33601.6639833601, 33828.0, 33876.49185262223, 33895.15262118442, 33927.0, 3303.504474328846, 3384.91104330944, 3398.4562470004926, 3459.066052165926, 3461.359648465042, 25690.229293121236, 25691.0, 25779.61330580024, 25846.741532584623, 26304.342391440176, 3417.3507033663773, 3535.5580552430947, 3537.327907697555, 3551.5205447264807, 3659.9634003660153, 225044.0, 225449.0, 226400.0, 226708.0, 226834.0, 39498.40752388659, 40420.393694094615, 42532.0, 42681.35977960333, 45541.77229113833, 46185.38146185422, 48829.755851220514, 49543.50456495389, 49974.500254997714, 50118.9976200474, 12196.268223906594, 12323.0, 12368.75262494745, 12370.319632420124, 12380.81428778569, 8890.688825891062, 8891.066438023914, 8898.955505222433, 8954.86567701485, 8979.685711000084, 15665.023645507317, 15752.842471575366, 15771.68456630861, 15778.526644200692, 15812.841871581137, 6419.582727122722, 6511.15355003856, 6566.507511936624, 6686.130802995676, 6688.197416310074, 786.4691333349966, 801.2228138705563, 801.7073768074611, 802.5987006496752, 810.643316940556, 26105.869470652524, 26914.5962810558, 27456.90172393122, 27815.443691126064, 28032.299192519942, 941.8398872191743, 952.1097773581723, 954.055485069776, 955.5890966884167, 1061.9893801062044, 13692.931535342459, 14240.077589525488, 14382.35279412429, 14603.488877889224, 14635.414583416758, 315338.0, 316766.0, 316773.0, 316880.662386751, 319838.0, 48065.27902081471, 48547.271790923165, 48666.75666621715, 48740.51259487431, 48748.5125148751, 1179.8643156036994, 1205.1202622085927, 1211.8121691137712, 1222.0345926717794, 1225.577175874322, 23210.419739506648, 24310.270691879272, 25050.74949250557, 25264.621030684186, 27234.72765272322, 40609.79695101506, 41091.383629244985, 41125.79437102795, 41176.17647646971, 41181.382279266436, 295835.0, 299595.0, 299787.0, 299871.0, 299934.0, 22877.428064298525, 23562.764372356058, 23650.29049128529, 23922.162724304566, 24678.629820553066, 36743.26513469662, 36801.0, 36993.44509832408, 37257.62742372542, 37884.0, 31156.688433116284, 31321.530177046905, 31413.52879706852, 31618.367632647678, 32028.03915882527, 30449.0, 31627.841860790548, 31853.522197166596, 31864.36271274515, 32109.03672889817, 29756.55365169481, 30099.548506772855, 30207.395852082394, 30514.542281866234, 30588.388232234785, 26420.735792642598, 26953.460930780882, 28357.0, 28401.715982839905, 28410.289742756595, 21819.781802181777, 21899.233526826483, 22165.778342217018, 22197.22309719152, 22331.44171395728], "value": 16657.181714427832, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.79955523350253, 393.4020289160475, 394.899300678327, 401.29172011399874, 420.35895259728926, 16468.58828529286, 17261.741073883903, 17833.732494012602, 17967.371142010034, 18080.095995200238, 11250.099992000642, 11276.774464510705, 11517.539298428073, 11586.710332241677, 11625.128115391337, 4772.2125849234835, 4916.336294600237, 4935.679180853233, 4960.950390496085, 4967.354243948282, 4750.8597936495335, 4806.110869489138, 4874.536918992708, 4906.9263961040615, 4923.163062279403, 4901.460839307673, 4912.36139301892, 4925.901481970359, 4928.186849169901, 4931.778069986859, 4764.261539461354, 4857.208224328808, 4883.389576302962, 4890.193118135503, 4914.901701965959, 4802.975985120087, 4803.0, 4809.5430934061205, 4812.230043193107, 4821.445533763627, 12905.67735806603, 12979.41592628334, 13036.608901732963, 13080.86919130806, 13274.46902123914, 2377.7265614454386, 2456.312232574882, 2501.2371226775826, 2501.7998560115147, 2511.7864981476414, 9145.314101442418, 9544.141027307576, 9703.0, 10059.044390782863, 10064.597416103346, 1757.2707326459504, 1775.316503146298, 1797.4158398520403, 1799.2802878848433, 1801.2974939773499, 75220.62389688016, 75288.0, 76246.61876690581, 76327.61836190784, 77000.61499692466, 10257.692269231786, 10417.562204472506, 10652.893471065345, 10752.086072683753, 10974.945125274322, 15006.249687515581, 15016.549503514912, 15067.924660376628, 15077.246137693071, 15101.622459438602, 6505.60966342021, 6675.297799061208, 6859.108315918998, 6972.2330543640155, 6972.755953541602, 6106.603070800384, 6116.724747386378, 6256.155419018374, 6307.274663413767, 6311.779087731908, 4552.453705555355, 4611.584957353854, 4657.767111644404, 4670.556297151833, 4672.556107169881, 1948.6687263165013, 1956.5010922215029, 1977.0656393776283, 2034.104993802722, 2051.579426217622, 803.935685145187, 820.8645573480428, 821.9219174178444, 850.889384380039, 861.7587075618836, 16636.0, 16952.0, 17036.574085647957, 17263.0, 17793.0, 809.2069771623842, 809.2595275323014, 819.2626636027622, 820.8399362124328, 842.8482873082904, 8984.0, 9341.953290233641, 9542.52287385628, 9712.514374281258, 9789.314747967715, 464986.0, 466376.0, 467007.0, 469111.0, 469993.0, 85619.0, 85663.57168214244, 86040.56979715062, 86879.56560217158, 86930.13069869347, 938.1837801113076, 938.4556956964944, 974.6539978307599, 975.8194733974237, 977.4282045003611, 14535.982481226212, 14639.926800366356, 14922.626934326729, 15269.160196189316, 15627.296771645077, 34987.47518787169, 35158.82420587881, 36623.45064823976, 40352.596474034886, 40581.18837623172, 50725.74637126791, 51965.0, 52723.47276527339, 53292.200616991555, 54379.45620543744, 7597.392208623407, 7686.192949740421, 7789.844203116019, 7794.532328060334, 8290.709825156091, 9854.852177217492, 11298.49156787963, 11326.603568875049, 11493.770124597628, 12210.0, 9324.160825525736, 9362.859557106513, 9693.854592181264, 9696.75758106053, 9845.753856153653, 9824.45965471906, 9831.606735730491, 10020.348677336242, 10023.797144342725, 10121.139703125316, 9352.625894964125, 9442.10300021497, 9569.425834449956, 9854.802903942025, 9908.653197138066, 6681.365270299314, 6865.691043903136, 7059.399951004222, 7204.207537171012, 8411.0, 9094.0, 10365.844512332473, 10393.116585090049, 10546.947265263625, 10916.454177291262], "value": 8071.312569086963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.4574051768516, 1319.8350206224222, 1325.9337033148347, 1327.8713093870213, 1335.6594068512532, 7118.430525557949, 7297.124345078598, 7487.513311634753, 7568.129665088516, 7589.051368578921, 7595.924040759591, 7661.348785353238, 7706.036745406825, 7713.0, 7717.0, 2045.7136000959877, 2101.8843963582, 2102.106604693007, 2105.694674272237, 2110.282503948657, 2050.4258807533915, 2090.571432856261, 2104.042660589428, 2109.0930899713117, 2115.4394085567337, 2038.3884834549576, 2057.855950083495, 2067.2454554087726, 2081.9583608327825, 2083.468715477551, 2067.080149333547, 2082.208760670935, 2085.979140208601, 2086.426232785981, 2087.488565301496, 2009.7186393904865, 2038.2865996901114, 2088.3212955789277, 2102.852800303979, 2140.464883779055, 7613.733519326797, 7667.846643067163, 7678.0, 7692.076950765888, 7704.65329060188, 4508.098380323949, 4625.0, 4646.86059418218, 4650.511696271877, 4652.953470465286, 2708.702042775293, 2855.0, 2890.6675732290637, 2936.4127174565174, 2943.882244710209, 1060.8620879285643, 1123.152020224725, 1145.2555838704825, 1145.2670291013758, 1151.4185336405146, 8675.349348798865, 9070.727878163732, 9955.352902061342, 10017.949910250474, 10059.245556583212, 3903.921921561553, 4042.191561687674, 4075.2664520386616, 4103.0, 4110.0752330725345, 8032.357411407073, 8058.476199047161, 8058.597070146469, 8070.1526339734655, 8071.152528984489, 4537.0, 4567.885802854888, 4570.360149579062, 4570.7943142558, 4575.9771201143785, 4091.8363265469047, 4100.507939047334, 4188.539260681323, 4191.559886211965, 4210.179015092089, 3169.176014236269, 3217.8069315841126, 3256.4626836572174, 3294.0447270291825, 3305.8347082645773, 969.3602222533094, 986.0730912941812, 992.8163289791412, 997.5361456922566, 1000.5347513406301, 738.1695592458484, 751.9285667861544, 762.6529928882345, 764.1746913333586, 782.1083964280724, 4687.10944920464, 4763.833265835679, 4818.10864989978, 5069.645124841226, 5080.364954380702, 849.2356878808998, 902.6615019367737, 908.327837400319, 910.2990697163148, 918.6555041859302, 3201.6638252983566, 3343.481760327153, 3367.478040903664, 3378.273671160712, 3526.5239192708655, 9062.003179650234, 9103.226225770752, 9149.359544831797, 9152.63389464414, 9201.30990175726, 8901.73294801157, 9078.90921090794, 9085.091490850862, 9113.954430227806, 9115.772105697411, 6626.337366263431, 6644.900326495106, 6832.795016149524, 6925.272846351161, 6942.028116063856, 4895.975520122377, 5054.772535235997, 5103.209002604603, 5208.374995000473, 5455.618106732491, 7870.305668137514, 8034.959825200836, 8093.219491711814, 8280.751577452686, 8286.958565207135, 7250.564966102051, 7976.521408715495, 8378.371622128243, 8385.790355241168, 8386.161383861565, 6616.437602803623, 6791.45668346541, 6913.308669133046, 6929.0, 6945.756898508528, 6850.280720524273, 6957.547759395623, 7024.157101147691, 7114.6442677866935, 7125.216226215216, 6502.869942601216, 6531.542792004514, 6540.607563546202, 6689.765858195135, 6699.19609646826, 6380.649064301508, 6401.135846660642, 6468.6442245676935, 6507.642079685663, 6568.27748947625, 5709.314882214161, 5802.448767367094, 5812.912806307824, 5818.534517238526, 5821.650700957786, 4479.148961697267, 4501.752403617701, 4506.211413002777, 4558.8632341029825, 4598.701084429501, 4287.6998610097, 4384.188925048876, 4411.558844115562, 4614.377059096979, 4700.153972285021], "value": 3959.5081359383453, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.5962772625344, 362.2465272233754, 366.75060958548187, 374.77326217638347, 378.6497489821914, 5051.797928082877, 5065.0, 5099.209622508512, 5100.693958362501, 5106.183010718286, 4970.105381031413, 4996.600271978242, 5013.846815232499, 5037.1940489521685, 5080.542751152396, 3670.6696397324304, 3863.497745293122, 3872.186840763443, 3906.6484016438517, 3910.1788624388914, 3866.845326186963, 3892.3383024885698, 3895.0067732728116, 3902.2390633826417, 3907.472491213678, 3853.7880416577077, 3863.497745293108, 3866.671332936704, 3876.3022655921923, 3892.5718171001167, 3632.164602141496, 3853.7880416577077, 3889.160912533852, 3900.4539364488996, 3905.0, 3377.4596064629786, 3378.864845406181, 3389.9661003389897, 3393.7454690898035, 3411.880584179542, 4893.87440888594, 4903.362562866841, 4917.434495033045, 4920.434150072716, 4927.610109097085, 4445.555444455561, 4454.5990860822685, 4479.417675702138, 4484.955150448486, 4490.528494508062, 5083.745812709387, 5302.496262855023, 5305.204219367087, 5323.574114070904, 5326.866828329284, 3986.7807270600106, 3992.960070399317, 4010.6991975601654, 4011.338129208677, 4020.0, 5301.893962120736, 5426.810061647863, 5581.8604534886545, 5600.551955843523, 5606.747696353633, 3889.611038896088, 3955.6835453163676, 4007.6994225432627, 4009.518857737032, 4025.1144748155357, 4066.776327302027, 4072.8778136655947, 4081.1635913040154, 4103.563752686536, 4139.958600414017, 4528.366028755977, 4604.30935359692, 4611.8847028823875, 4619.8845028873875, 4640.907181856344, 5325.349141766065, 5328.8276579152525, 5339.466053394707, 5380.650257733235, 5381.192821076792, 1383.8546952570036, 1396.9092009019585, 1407.6973450708147, 1409.8237720284965, 1422.879055280292, 966.950858318732, 1012.250934308621, 1017.0, 1030.8866024737272, 1031.2111234905303, 4486.237339652267, 4506.436695413074, 4524.954750452519, 4535.824922554225, 4545.863444181229, 5270.209468579667, 5309.946900531023, 5313.760880760375, 5339.679619222859, 5356.018273238927, 4332.871755929808, 4470.664700147502, 4575.519570445122, 4580.312953057068, 4605.769711514478, 2770.7644850187958, 2810.6627204735564, 2812.971870281312, 2815.5354366529295, 2818.8590570471392, 4898.681585696918, 4913.385826771653, 4919.188333924933, 4930.654854160174, 4961.454240033593, 5028.812466577051, 5367.78528858849, 5388.757505912244, 5391.487808658171, 5418.078926582491, 4851.490593487633, 4875.269279405831, 4899.608031357483, 4901.754912254373, 4906.411230652345, 5204.219367094966, 5235.397929238186, 5239.214117882349, 5242.824227945513, 5280.815171469134, 4977.502249774993, 5142.6914385137015, 5194.805194805292, 5201.869953251199, 5218.191180367049, 4804.735739534359, 4869.269609558594, 4891.777055736066, 4897.155732749774, 4930.161872481758, 4043.353063509824, 4091.629304183132, 4097.037196258936, 4111.48606424197, 4118.691098167709, 5432.945670543244, 5454.372747133972, 5456.181572763959, 5460.6996615186545, 5460.836174914759, 5115.411727651368, 5249.868753281045, 5268.341457317835, 5311.601629877698, 5327.840164795062, 5213.244079608359, 5216.869578260574, 5217.373915130057, 5218.407801134977, 5230.869228269324, 5151.6908985461, 5160.251763494347, 5176.818811341585, 5184.248283998874, 5219.739013049409, 5024.447310795883, 5177.456367081553, 5179.0, 5203.346293390323, 5290.1800220965815, 5191.32512773337, 5216.452812087665, 5217.191335343028, 5229.764660590206, 5244.082285599929], "value": 3994.8829058458305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.098067608728, 673.807964730052, 734.1483878700705, 748.7080038784873, 759.6847308367027, 12502.249865008094, 14048.438062477515, 14074.648133796634, 14079.577612671636, 14091.365888535014, 13029.934850325759, 13104.0, 13121.671958201026, 13125.67185820355, 13180.868191318084, 753.9886901696451, 797.1351084073787, 806.1615919443801, 808.5350923219144, 812.2567850416891, 824.7154731617583, 824.7938015496126, 825.352098602597, 826.2150956591232, 828.7306625346756, 849.7918010087507, 854.0605334132466, 854.2226573817827, 854.3208149521146, 856.9100244474334, 851.8551846186164, 853.2193043365313, 854.3250831842868, 855.2943821347383, 857.618359829876, 622.6015350175891, 623.5603899251017, 624.213491001337, 624.7282432142033, 624.8188025472652, 13658.590242292648, 13908.374123164482, 13938.163710177321, 13946.442142314396, 14000.0, 12208.32854193019, 12420.378981050912, 12469.812952805714, 12717.427715752814, 12975.351232438436, 10487.370757754483, 10625.9468702656, 10631.84052239217, 10740.624078157305, 10902.454877256185, 3143.4813255812765, 3152.6374466936136, 3234.450143475615, 3292.0, 3301.735861131104, 60264.0, 60749.69625151846, 61101.0, 62085.068723969176, 62085.37914620841, 9704.320697551244, 9891.208703303862, 9900.7029789105, 9919.553620087114, 9960.601575937026, 16846.07346595949, 16972.15139243033, 17044.744328835077, 17202.913985430245, 17328.566785830455, 6061.0, 6400.7119679614525, 6414.294427612959, 6528.347165283433, 6577.80266592003, 3410.795352278871, 3550.0769799852224, 3585.15748799032, 3703.129764505339, 3705.27747089315, 2968.168912704446, 3048.0551029181024, 3070.5854709614364, 3072.324088700482, 3092.829894355787, 502.3845788908558, 503.0693217547508, 510.2983397827987, 513.6481510165548, 523.9764210610532, 595.7319206357157, 615.2404124204771, 618.5670030978243, 631.4506379449908, 631.5200447659812, 8346.833063338821, 8375.916240837636, 8860.601272942733, 8995.595198216097, 9410.153086222273, 638.8850006998784, 641.7753786174801, 666.6333516565869, 667.6194569095569, 668.2949488289901, 4205.894852628709, 4300.204462174508, 4319.935200971988, 4347.782898919258, 4427.800748966304, 48989.51010489921, 50268.49731502638, 62084.223984657525, 64296.0, 67244.3275567238, 55438.72280638652, 55534.0, 56152.43847561554, 56913.0, 57539.0, 48370.58147092702, 48691.75654121778, 49142.26286605704, 49555.50444495509, 50061.74969125131, 7984.6406911687955, 8336.87494687568, 8343.499390036617, 8366.958165209135, 8527.403081784463, 28505.28736781597, 30405.69594304117, 31795.523067154474, 32758.50862237116, 37355.252894942496, 42310.153796923274, 42791.57208428001, 43093.35359969666, 44549.55450445454, 44690.0, 11008.889911101107, 11265.718357040812, 11299.152563557602, 11497.08023358146, 11647.184697071463, 12358.011359091432, 12485.375731213584, 12532.62402127901, 12860.421281042563, 13148.211107333591, 11201.271917325348, 11659.300441973508, 11708.765824683629, 12033.518659253532, 12491.562795302121, 11768.82346764782, 11799.469023894117, 12007.399630018639, 12242.0, 12398.62804115878, 10932.523811180154, 10934.0, 11163.0, 11321.886781132083, 11471.541138354372, 9944.801103978025, 10004.499775011365, 10012.0, 10030.29787914839, 10346.741331466774, 8981.955090224506, 9562.808743825224, 9632.9036709632, 9704.913478329647, 9758.560864761244], "value": 6286.728926434496, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 123.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 134.0, 131.0, 182.0, 128.0, 130.0, 127.0, 127.0, 159.0, 128.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 166.0, 168.0, 174.0, 163.0, 164.0, 162.0, 169.0, 169.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 103.0, 79.0, 81.0, 82.0, 78.0, 97.0, 79.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 102.0, 85.0, 86.0, 84.0, 97.0, 86.0, 85.0, 107.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 114.0, 119.0, 114.0, 116.0, 115.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 151.0, 150.0, 151.0, 150.0, 152.0, 150.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 95.0, 96.0, 96.0, 97.0, 97.0, 95.0, 102.0, 100.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 70.0, 70.0, 67.0, 68.0, 68.0, 68.0, 67.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 68.0, 69.0, 65.0, 66.0, 66.0, 66.0, 67.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 92.0, 91.0, 92.0, 93.0, 90.0, 91.0, 89.0, 89.0, 90.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 140.0, 141.0, 137.0, 136.0, 144.0, 141.0, 145.0, 189.0, 150.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 191.0, 192.0, 186.0, 187.0, 189.0, 188.0, 191.0, 187.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 70.0, 71.0, 73.0, 75.0, 74.0, 75.0, 73.0, 69.0], "value": 72.3, "unit": "score"}]}]} 08:23:00 INFO - Return code: 0 08:23:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:23:00 ERROR - # TBPL SUCCESS # 08:23:00 INFO - Running post-action listener: _resource_record_post_action 08:23:00 INFO - [mozharness: 2017-02-21 16:23:00.314763Z] Finished run-tests step (success) 08:23:00 INFO - Running post-run listener: _resource_record_post_run 08:23:00 INFO - Total resource usage - Wall time: 1201s; CPU: 14.0%; Read bytes: 237568; Write bytes: 372912128; Read time: 188; Write time: 314552 08:23:00 INFO - TinderboxPrint: CPU usage
13.5% 08:23:00 INFO - TinderboxPrint: I/O read bytes / time
237,568 / 188 08:23:00 INFO - TinderboxPrint: I/O write bytes / time
372,912,128 / 314,552 08:23:00 INFO - TinderboxPrint: CPU idle
8,288.9 (86.5%) 08:23:00 INFO - TinderboxPrint: CPU user
1,238.6 (12.9%) 08:23:00 INFO - TinderboxPrint: Swap in / out
0 / 0 08:23:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 116678656; Read time: 0; Write time: 145840 08:23:00 INFO - run-tests - Wall time: 1185s; CPU: 14.0%; Read bytes: 233472; Write bytes: 256233472; Read time: 176; Write time: 168712 08:23:00 INFO - Running post-run listener: _upload_blobber_files 08:23:00 INFO - Blob upload gear active. 08:23:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:23:00 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 08:23:00 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'] 08:23:00 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 08:23:01 INFO - (blobuploader) - INFO - Open directory for files ... 08:23:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:23:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:23:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:04 INFO - (blobuploader) - INFO - Done attempting. 08:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:05 INFO - (blobuploader) - INFO - Done attempting. 08:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:06 INFO - (blobuploader) - INFO - Done attempting. 08:23:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:23:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:23:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:07 INFO - (blobuploader) - INFO - Done attempting. 08:23:07 INFO - (blobuploader) - INFO - Iteration through files over. 08:23:07 INFO - Return code: 0 08:23:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:23:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:23:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea6d0ac350dd4d5bb3274ca834489be8c2c6da2b118fc4d23578b016946c0169f5591dcc53afb8f030c874ee7ae4d7ad9b4ef7399a663b299abdf4062c3c2d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2a94d55d97e34389eacb58d90309b731c77655c731ee88edcf2eee6bfda8802f4feead4b108dfad31e946c148c4de8ef2e220ad609553f13f1854990de0c6e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a22291044fdd7788d81ad6f1a9c0d848933ff503392dc951b7f76f914dedf1e191dc5a6db6f0af54a55f91054ceedf2ed256c6abc8cefaa57ae5ba86070bea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b4f189d5c07d6af779061d94ab19eb3d42f787294b35afb5d05220ff0d75b9c31cb0e9b156d625716506c853159c8c9b2fad78a3dd8404044e78e4aca82b749"} 08:23:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:23:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:23:07 INFO - Contents: 08:23:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea6d0ac350dd4d5bb3274ca834489be8c2c6da2b118fc4d23578b016946c0169f5591dcc53afb8f030c874ee7ae4d7ad9b4ef7399a663b299abdf4062c3c2d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2a94d55d97e34389eacb58d90309b731c77655c731ee88edcf2eee6bfda8802f4feead4b108dfad31e946c148c4de8ef2e220ad609553f13f1854990de0c6e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a22291044fdd7788d81ad6f1a9c0d848933ff503392dc951b7f76f914dedf1e191dc5a6db6f0af54a55f91054ceedf2ed256c6abc8cefaa57ae5ba86070bea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b4f189d5c07d6af779061d94ab19eb3d42f787294b35afb5d05220ff0d75b9c31cb0e9b156d625716506c853159c8c9b2fad78a3dd8404044e78e4aca82b749"} 08:23:07 INFO - Running post-run listener: copy_logs_to_upload_dir 08:23:07 INFO - Copying logs to upload dir... 08:23:07 INFO - mkdir: /builds/slave/test/build/upload/logs 08:23:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.749432 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-21 08:23:07.479122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 08:23:07.482774) ========= 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=03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea6d0ac350dd4d5bb3274ca834489be8c2c6da2b118fc4d23578b016946c0169f5591dcc53afb8f030c874ee7ae4d7ad9b4ef7399a663b299abdf4062c3c2d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2a94d55d97e34389eacb58d90309b731c77655c731ee88edcf2eee6bfda8802f4feead4b108dfad31e946c148c4de8ef2e220ad609553f13f1854990de0c6e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a22291044fdd7788d81ad6f1a9c0d848933ff503392dc951b7f76f914dedf1e191dc5a6db6f0af54a55f91054ceedf2ed256c6abc8cefaa57ae5ba86070bea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b4f189d5c07d6af779061d94ab19eb3d42f787294b35afb5d05220ff0d75b9c31cb0e9b156d625716506c853159c8c9b2fad78a3dd8404044e78e4aca82b749"} build_url:https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/fWcnSoQdR1K5iB-xv_YRIg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea6d0ac350dd4d5bb3274ca834489be8c2c6da2b118fc4d23578b016946c0169f5591dcc53afb8f030c874ee7ae4d7ad9b4ef7399a663b299abdf4062c3c2d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec2a94d55d97e34389eacb58d90309b731c77655c731ee88edcf2eee6bfda8802f4feead4b108dfad31e946c148c4de8ef2e220ad609553f13f1854990de0c6e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a22291044fdd7788d81ad6f1a9c0d848933ff503392dc951b7f76f914dedf1e191dc5a6db6f0af54a55f91054ceedf2ed256c6abc8cefaa57ae5ba86070bea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b4f189d5c07d6af779061d94ab19eb3d42f787294b35afb5d05220ff0d75b9c31cb0e9b156d625716506c853159c8c9b2fad78a3dd8404044e78e4aca82b749"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 08:23:07.507874) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:23:07.508277) ========= 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=03627c79f6bb3d1578c4a402000001ba-1487692921.603021-764328254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004402 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 08:23:07.592354) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 08:23:07.592710) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 08:23:07.593052) ========= ========= Total master_lag: 0.17 =========