builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-089 starttime: 1487263255.21 results: success (0) revision: 25929185c46777cf4e9eb3e9aad2f8a8a227178f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.211885) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.212928) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.213657) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.247633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.248315) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-16 08:40:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.7M=0s 2017-02-16 08:40:55 (41.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130743 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.416458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.416831) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019892 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.469876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:55.470619) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25929185c46777cf4e9eb3e9aad2f8a8a227178f --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 25929185c46777cf4e9eb3e9aad2f8a8a227178f --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=8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-16 08:40:55,521 truncating revision to first 12 chars 2017-02-16 08:40:55,521 Setting DEBUG logging. 2017-02-16 08:40:55,521 attempt 1/10 2017-02-16 08:40:55,521 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25929185c467?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 08:40:55,825 unpacking tar archive at: mozilla-central-25929185c467/testing/mozharness/ program finished with exit code 0 elapsedTime=0.676282 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:56.164596) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:56.165006) ========= script_repo_revision: 25929185c46777cf4e9eb3e9aad2f8a8a227178f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:56.165458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:56.165772) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 08:40:56.175527) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-16 08:40:56.175846) ========= /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=8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:40:56 INFO - MultiFileLogger online at 20170216 08:40:56 in /builds/slave/test 08:40:56 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:40:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:40:56 INFO - {'append_to_log': False, 08:40:56 INFO - 'base_work_dir': '/builds/slave/test', 08:40:56 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:40:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:40:56 INFO - 'branch': 'Firefox-Non-PGO', 08:40:56 INFO - 'buildbot_json_path': 'buildprops.json', 08:40:56 INFO - 'config_files': ('talos/linux_config.py',), 08:40:56 INFO - 'default_actions': ('clobber', 08:40:56 INFO - 'read-buildbot-config', 08:40:56 INFO - 'download-and-extract', 08:40:56 INFO - 'populate-webroot', 08:40:56 INFO - 'create-virtualenv', 08:40:56 INFO - 'install', 08:40:56 INFO - 'run-tests'), 08:40:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:40:56 INFO - 'download_minidump_stackwalk': True, 08:40:56 INFO - 'download_symbols': 'ondemand', 08:40:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:40:56 INFO - 'tooltool.py': '/tools/tooltool.py', 08:40:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:40:56 INFO - '/tools/misc-python/virtualenv.py')}, 08:40:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:40:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:40:56 INFO - 'installer_path': 'installer.exe', 08:40:56 INFO - 'log_level': 'info', 08:40:56 INFO - 'log_name': 'talos', 08:40:56 INFO - 'log_to_console': True, 08:40:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:40:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:40:56 INFO - 'opt_config_files': (), 08:40:56 INFO - 'pip_index': False, 08:40:56 INFO - 'sps_profile': False, 08:40:56 INFO - 'sps_profile_interval': 0, 08:40:56 INFO - 'suite': 'dromaeojs-e10s', 08:40:56 INFO - 'system_bits': '32', 08:40:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:40:56 INFO - 'title': 'talos-linux64-ix-089', 08:40:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:40:56 INFO - 'use_talos_json': True, 08:40:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:40:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:40:56 INFO - 'work_dir': 'build'} 08:40:56 INFO - [mozharness: 2017-02-16 16:40:56.424448Z] Running clobber step. 08:40:56 INFO - Running pre-action listener: _resource_record_pre_action 08:40:56 INFO - Running main action method: clobber 08:40:56 INFO - rmtree: /builds/slave/test/build 08:40:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:40:56 INFO - Running post-action listener: _resource_record_post_action 08:40:56 INFO - [mozharness: 2017-02-16 16:40:56.890973Z] Finished clobber step (success) 08:40:56 INFO - [mozharness: 2017-02-16 16:40:56.891073Z] Running read-buildbot-config step. 08:40:56 INFO - Running pre-action listener: _resource_record_pre_action 08:40:56 INFO - Running main action method: read_buildbot_config 08:40:56 INFO - Using buildbot properties: 08:40:56 INFO - { 08:40:56 INFO - "project": "", 08:40:56 INFO - "product": "firefox", 08:40:56 INFO - "who": "ffxbld@noreply.mozilla.org", 08:40:56 INFO - "installer_path": "public/build/target.tar.bz2", 08:40:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:40:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:40:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:40:56 INFO - "stage_platform": "linux64", 08:40:56 INFO - "basedir": "/builds/slave/test", 08:40:56 INFO - "buildnumber": 422, 08:40:56 INFO - "platform": "ubuntu64_hw", 08:40:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:40:56 INFO - "slavebuilddir": "test", 08:40:56 INFO - "taskId": "aTM7IQD3QtecALnjmj7vmg", 08:40:56 INFO - "branch": "mozilla-central", 08:40:56 INFO - "script_repo_revision": "production", 08:40:56 INFO - "revision": "25929185c46777cf4e9eb3e9aad2f8a8a227178f", 08:40:56 INFO - "slavename": "talos-linux64-ix-089", 08:40:56 INFO - "repo_path": "mozilla-central" 08:40:56 INFO - } 08:40:56 INFO - Finding installer, test and symbols from parent task. 08:40:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aTM7IQD3QtecALnjmj7vmg'}, attempt #1 08:40:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aTM7IQD3QtecALnjmj7vmg'}, attempt #1 08:40:57 INFO - Task dependencies: GF__qv5TRRS2uPzZ7Tm1eg 08:40:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg'}, attempt #1 08:40:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2 08:40:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/test_packages.json 08:40:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.crashreporter-symbols.zip 08:40:58 INFO - Running post-action listener: _resource_record_post_action 08:40:58 INFO - [mozharness: 2017-02-16 16:40:58.300066Z] Finished read-buildbot-config step (success) 08:40:58 INFO - [mozharness: 2017-02-16 16:40:58.300283Z] Running download-and-extract step. 08:40:58 INFO - Running pre-action listener: _resource_record_pre_action 08:40:58 INFO - Running main action method: download_and_extract 08:40:58 INFO - mkdir: /builds/slave/test/build/tests 08:40: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:40:58 INFO - trying https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.test_packages.json 08:40:58 INFO - Downloading https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:40:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:40:58 INFO - Downloaded 896 bytes. 08:40:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:40:58 INFO - Using the following test package requirements: 08:40:58 INFO - {u'common': [u'target.common.tests.zip'], 08:40:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:40:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:40:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:40:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:40:58 INFO - u'mozbase': [u'target.common.tests.zip'], 08:40:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:40:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:40:58 INFO - u'web-platform': [u'target.common.tests.zip', 08:40:58 INFO - u'target.web-platform.tests.zip'], 08:40:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:40:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:40:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.common.tests.zip 08:40:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:40:58 INFO - Fetch https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.common.tests.zip into memory 08:41:00 INFO - Expected file size: 57034347 08:41:00 INFO - Obtained file size: 57034347 08:41:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:41:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.common.tests.zip 08:41:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:41:09 INFO - Fetch https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.common.tests.zip into memory 08:41:11 INFO - Expected file size: 57034347 08:41:11 INFO - Obtained file size: 57034347 08:41:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.talos.tests.zip 08:41:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:41:21 INFO - Fetch https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.talos.tests.zip into memory 08:41:22 INFO - Expected file size: 14403647 08:41:22 INFO - Obtained file size: 14403647 08:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:22 INFO - trying https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2 08:41:22 INFO - Downloading https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:41:24 INFO - Downloaded 56333909 bytes. 08:41:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2 08:41:24 INFO - mkdir: /builds/slave/test/properties 08:41:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:41:24 INFO - Writing to file /builds/slave/test/properties/build_url 08:41:24 INFO - Contents: 08:41:24 INFO - build_url:https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2 08:41:24 INFO - Running post-action listener: _resource_record_post_action 08:41:24 INFO - Running post-action listener: set_extra_try_arguments 08:41:24 INFO - [mozharness: 2017-02-16 16:41:24.028639Z] Finished download-and-extract step (success) 08:41:24 INFO - [mozharness: 2017-02-16 16:41:24.028837Z] Running populate-webroot step. 08:41:24 INFO - Running pre-action listener: _resource_record_pre_action 08:41:24 INFO - Running main action method: populate_webroot 08:41:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:41:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:41:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:41:24 INFO - '--tppagecycles', 08:41:24 INFO - '7'], 08:41:24 INFO - 'tests': ['tsvgm']}}, 08:41:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:41:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:41:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:41:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:41:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:41:24 INFO - 'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:41:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:41:24 INFO - 'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['damp', 'tps']}, 08:41:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:41:24 INFO - 'tests': ['damp', 'tps']}, 08:41:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['dromaeo_dom']}, 08:41:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:41:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['basic_compositor_video']}, 08:41:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:41:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['a11yr', 08:41:24 INFO - 'ts_paint', 08:41:24 INFO - 'tpaint', 08:41:24 INFO - 'sessionrestore', 08:41:24 INFO - 'sessionrestore_no_auto_restore', 08:41:24 INFO - 'tabpaint']}, 08:41:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:41:24 INFO - 'ts_paint', 08:41:24 INFO - 'tpaint', 08:41:24 INFO - 'sessionrestore', 08:41:24 INFO - 'sessionrestore_no_auto_restore', 08:41:24 INFO - 'tabpaint']}, 08:41:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:41:24 INFO - 'ts_paint', 08:41:24 INFO - 'tpaint', 08:41:24 INFO - 'sessionrestore', 08:41:24 INFO - 'sessionrestore_no_auto_restore', 08:41:24 INFO - 'tabpaint']}, 08:41:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:41:24 INFO - 'ts_paint', 08:41:24 INFO - 'tpaint', 08:41:24 INFO - 'sessionrestore', 08:41:24 INFO - 'sessionrestore_no_auto_restore', 08:41:24 INFO - 'tabpaint']}, 08:41:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['a11yr', 08:41:24 INFO - 'ts_paint', 08:41:24 INFO - 'tpaint', 08:41:24 INFO - 'sessionrestore', 08:41:24 INFO - 'sessionrestore_no_auto_restore', 08:41:24 INFO - 'tabpaint']}, 08:41:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['a11yr', 08:41:24 INFO - 'ts_paint', 08:41:24 INFO - 'tpaint', 08:41:24 INFO - 'sessionrestore', 08:41:24 INFO - 'sessionrestore_no_auto_restore', 08:41:24 INFO - 'tabpaint']}, 08:41:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['tsvgx', 08:41:24 INFO - 'tsvgr_opacity', 08:41:24 INFO - 'tart', 08:41:24 INFO - 'tscrollx', 08:41:24 INFO - 'cart', 08:41:24 INFO - 'tsvg_static']}, 08:41:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:41:24 INFO - 'tsvgr_opacity', 08:41:24 INFO - 'tart', 08:41:24 INFO - 'tscrollx', 08:41:24 INFO - 'cart', 08:41:24 INFO - 'tsvg_static']}, 08:41:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:41:24 INFO - 'talos_options': ['--disable-e10s'], 08:41:24 INFO - 'tests': ['tp5o']}, 08:41:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:41:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:41:24 INFO - 'talos_options': ['--disable-e10s', 08:41:24 INFO - '--xperf_path', 08:41:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:24 INFO - 'tests': ['tp5n']}, 08:41:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:41:24 INFO - 'talos_options': ['--xperf_path', 08:41:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:24 INFO - 'tests': ['tp5n']}}, 08:41:24 INFO - 'talos.zip': {'path': '', 08:41:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:41:24 INFO - Running post-action listener: _resource_record_post_action 08:41:24 INFO - [mozharness: 2017-02-16 16:41:24.042051Z] Finished populate-webroot step (success) 08:41:24 INFO - [mozharness: 2017-02-16 16:41:24.042142Z] Running create-virtualenv step. 08:41:24 INFO - Running pre-action listener: _resource_record_pre_action 08:41:24 INFO - Running main action method: create_virtualenv 08:41:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:41:24 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:41:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:41:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:41:24 INFO - Using real prefix '/usr' 08:41:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:41:24 INFO - Installing distribute.............................................................................................................................................................................................done. 08:41:26 INFO - Installing pip.................done. 08:41:26 INFO - Return code: 0 08:41:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:41:27 INFO - Reading from file tmpfile_stdout 08:41:27 INFO - Output received: 08:41:27 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:41:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:41:27 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:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:27 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:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:27 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:27 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:41:27 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:41:27 INFO - Using env: {'DISPLAY': ':0', 08:41:27 INFO - 'HOME': '/home/cltbld', 08:41:27 INFO - 'LANG': 'en_US.UTF-8', 08:41:27 INFO - 'LANGUAGE': 'en_US:en', 08:41:27 INFO - 'LOGNAME': 'cltbld', 08:41:27 INFO - 'MAIL': '/var/mail/cltbld', 08:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:27 INFO - 'MOZ_NO_REMOTE': '1', 08:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:27 INFO - 'NO_EM_RESTART': '1', 08:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:27 INFO - 'PWD': '/builds/slave/test', 08:41:27 INFO - 'SHELL': '/bin/bash', 08:41:27 INFO - 'SHLVL': '1', 08:41:27 INFO - 'TERM': 'linux', 08:41:27 INFO - 'TMOUT': '86400', 08:41:27 INFO - 'USER': 'cltbld', 08:41:27 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:27 INFO - '_': '/tools/buildbot/bin/python'} 08:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:28 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:41:28 INFO - Cleaning up... 08:41:28 INFO - Return code: 0 08:41:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:41:28 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:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:28 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:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:28 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:28 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:41:28 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:41:28 INFO - Using env: {'DISPLAY': ':0', 08:41:28 INFO - 'HOME': '/home/cltbld', 08:41:28 INFO - 'LANG': 'en_US.UTF-8', 08:41:28 INFO - 'LANGUAGE': 'en_US:en', 08:41:28 INFO - 'LOGNAME': 'cltbld', 08:41:28 INFO - 'MAIL': '/var/mail/cltbld', 08:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:28 INFO - 'MOZ_NO_REMOTE': '1', 08:41:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:28 INFO - 'NO_EM_RESTART': '1', 08:41:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:28 INFO - 'PWD': '/builds/slave/test', 08:41:28 INFO - 'SHELL': '/bin/bash', 08:41:28 INFO - 'SHLVL': '1', 08:41:28 INFO - 'TERM': 'linux', 08:41:28 INFO - 'TMOUT': '86400', 08:41:28 INFO - 'USER': 'cltbld', 08:41:28 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:28 INFO - '_': '/tools/buildbot/bin/python'} 08:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:28 INFO - Downloading/unpacking psutil>=3.1.1 08:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:41:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:30 INFO - Installing collected packages: psutil 08:41:30 INFO - Running setup.py install for psutil 08:41:30 INFO - building 'psutil._psutil_linux' extension 08:41:30 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:41:31 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:41:31 INFO - building 'psutil._psutil_posix' extension 08:41:31 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:41:31 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:41:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:31 INFO - Successfully installed psutil 08:41:31 INFO - Cleaning up... 08:41:31 INFO - Return code: 0 08:41:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:41:31 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:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:31 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:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:31 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:31 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:41:31 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:41:31 INFO - Using env: {'DISPLAY': ':0', 08:41:31 INFO - 'HOME': '/home/cltbld', 08:41:31 INFO - 'LANG': 'en_US.UTF-8', 08:41:31 INFO - 'LANGUAGE': 'en_US:en', 08:41:31 INFO - 'LOGNAME': 'cltbld', 08:41:31 INFO - 'MAIL': '/var/mail/cltbld', 08:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:31 INFO - 'MOZ_NO_REMOTE': '1', 08:41:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:31 INFO - 'NO_EM_RESTART': '1', 08:41:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:31 INFO - 'PWD': '/builds/slave/test', 08:41:31 INFO - 'SHELL': '/bin/bash', 08:41:31 INFO - 'SHLVL': '1', 08:41:31 INFO - 'TERM': 'linux', 08:41:31 INFO - 'TMOUT': '86400', 08:41:31 INFO - 'USER': 'cltbld', 08:41:31 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:31 INFO - '_': '/tools/buildbot/bin/python'} 08:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:41:31 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:41:31 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:41:31 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:41:31 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:41:31 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:41:31 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:41:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:41:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:41:36 INFO - Installing collected packages: mozsystemmonitor 08:41:36 INFO - Running setup.py install for mozsystemmonitor 08:41:36 INFO - Successfully installed mozsystemmonitor 08:41:36 INFO - Cleaning up... 08:41:37 INFO - Return code: 0 08:41:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:41:37 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:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:37 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:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:37 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:37 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:41:37 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:41:37 INFO - Using env: {'DISPLAY': ':0', 08:41:37 INFO - 'HOME': '/home/cltbld', 08:41:37 INFO - 'LANG': 'en_US.UTF-8', 08:41:37 INFO - 'LANGUAGE': 'en_US:en', 08:41:37 INFO - 'LOGNAME': 'cltbld', 08:41:37 INFO - 'MAIL': '/var/mail/cltbld', 08:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:37 INFO - 'MOZ_NO_REMOTE': '1', 08:41:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:37 INFO - 'NO_EM_RESTART': '1', 08:41:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:37 INFO - 'PWD': '/builds/slave/test', 08:41:37 INFO - 'SHELL': '/bin/bash', 08:41:37 INFO - 'SHLVL': '1', 08:41:37 INFO - 'TERM': 'linux', 08:41:37 INFO - 'TMOUT': '86400', 08:41:37 INFO - 'USER': 'cltbld', 08:41:37 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:37 INFO - '_': '/tools/buildbot/bin/python'} 08:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:41:37 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:41:37 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:41:37 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:41:37 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:41:37 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:41:37 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:41:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:41:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:41:39 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:41:39 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:41:39 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:41:39 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:41:39 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:41:39 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:41:39 INFO - Downloading functools32-3.2.3-2.tar.gz 08:41:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:41:39 INFO - warning: no files found matching '*.txt' 08:41:39 INFO - no previously-included directories found matching 'build' 08:41:39 INFO - no previously-included directories found matching 'dist' 08:41:39 INFO - no previously-included directories found matching '.git*' 08:41:39 INFO - Installing collected packages: jsonschema, functools32 08:41:39 INFO - Running setup.py install for functools32 08:41:39 INFO - warning: no files found matching '*.txt' 08:41:39 INFO - no previously-included directories found matching 'build' 08:41:39 INFO - no previously-included directories found matching 'dist' 08:41:39 INFO - no previously-included directories found matching '.git*' 08:41:39 INFO - Successfully installed jsonschema functools32 08:41:39 INFO - Cleaning up... 08:41:39 INFO - Return code: 0 08:41:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:39 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:41:39 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:41:39 INFO - Using env: {'DISPLAY': ':0', 08:41:39 INFO - 'HOME': '/home/cltbld', 08:41:39 INFO - 'LANG': 'en_US.UTF-8', 08:41:39 INFO - 'LANGUAGE': 'en_US:en', 08:41:39 INFO - 'LOGNAME': 'cltbld', 08:41:39 INFO - 'MAIL': '/var/mail/cltbld', 08:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:39 INFO - 'MOZ_NO_REMOTE': '1', 08:41:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:39 INFO - 'NO_EM_RESTART': '1', 08:41:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:39 INFO - 'PWD': '/builds/slave/test', 08:41:39 INFO - 'SHELL': '/bin/bash', 08:41:39 INFO - 'SHLVL': '1', 08:41:39 INFO - 'TERM': 'linux', 08:41:39 INFO - 'TMOUT': '86400', 08:41:39 INFO - 'USER': 'cltbld', 08:41:39 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:39 INFO - '_': '/tools/buildbot/bin/python'} 08:41:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:41:40 INFO - Cleaning up... 08:41:40 INFO - Return code: 0 08:41:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:41:40 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:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:40 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:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:40 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:40 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:41:40 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:41:40 INFO - Using env: {'DISPLAY': ':0', 08:41:40 INFO - 'HOME': '/home/cltbld', 08:41:40 INFO - 'LANG': 'en_US.UTF-8', 08:41:40 INFO - 'LANGUAGE': 'en_US:en', 08:41:40 INFO - 'LOGNAME': 'cltbld', 08:41:40 INFO - 'MAIL': '/var/mail/cltbld', 08:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:40 INFO - 'MOZ_NO_REMOTE': '1', 08:41:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:40 INFO - 'NO_EM_RESTART': '1', 08:41:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:40 INFO - 'PWD': '/builds/slave/test', 08:41:40 INFO - 'SHELL': '/bin/bash', 08:41:40 INFO - 'SHLVL': '1', 08:41:40 INFO - 'TERM': 'linux', 08:41:40 INFO - 'TMOUT': '86400', 08:41:40 INFO - 'USER': 'cltbld', 08:41:40 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:40 INFO - '_': '/tools/buildbot/bin/python'} 08:41:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:41:40 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:41:40 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:41:40 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:41:40 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:41:40 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:41:40 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:41:42 INFO - Downloading blobuploader-1.2.4.tar.gz 08:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:41:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:41: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:41: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:41: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:41: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:41: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:41: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:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:41:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:41: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:41: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:41: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:41: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:41: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:41: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:41:43 INFO - Downloading docopt-0.6.1.tar.gz 08:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:41:43 INFO - Installing collected packages: blobuploader, requests, docopt 08:41:43 INFO - Running setup.py install for blobuploader 08:41:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:41:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:41:43 INFO - Running setup.py install for requests 08:41:43 INFO - Running setup.py install for docopt 08:41:43 INFO - Successfully installed blobuploader requests docopt 08:41:43 INFO - Cleaning up... 08:41:43 INFO - Return code: 0 08:41:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:43 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:43 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:41:43 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:41:43 INFO - Using env: {'DISPLAY': ':0', 08:41:43 INFO - 'HOME': '/home/cltbld', 08:41:43 INFO - 'LANG': 'en_US.UTF-8', 08:41:43 INFO - 'LANGUAGE': 'en_US:en', 08:41:43 INFO - 'LOGNAME': 'cltbld', 08:41:43 INFO - 'MAIL': '/var/mail/cltbld', 08:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:43 INFO - 'MOZ_NO_REMOTE': '1', 08:41:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:43 INFO - 'NO_EM_RESTART': '1', 08:41:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:43 INFO - 'PWD': '/builds/slave/test', 08:41:43 INFO - 'SHELL': '/bin/bash', 08:41:43 INFO - 'SHLVL': '1', 08:41:43 INFO - 'TERM': 'linux', 08:41:43 INFO - 'TMOUT': '86400', 08:41:43 INFO - 'USER': 'cltbld', 08:41:43 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:43 INFO - '_': '/tools/buildbot/bin/python'} 08:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:44 INFO - Running setup.py (path:/tmp/pip-5CK3yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:44 INFO - Running setup.py (path:/tmp/pip-fX3nng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:44 INFO - Running setup.py (path:/tmp/pip-DpCn4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:44 INFO - Running setup.py (path:/tmp/pip-RNtiMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:44 INFO - Running setup.py (path:/tmp/pip-9gnkbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:44 INFO - Running setup.py (path:/tmp/pip-glG5Yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:44 INFO - Running setup.py (path:/tmp/pip-dOWso_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:44 INFO - Running setup.py (path:/tmp/pip-QFw3B_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:44 INFO - Running setup.py (path:/tmp/pip-zQu5T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:44 INFO - Running setup.py (path:/tmp/pip-_FyJQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:44 INFO - Running setup.py (path:/tmp/pip-qK4Y3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:44 INFO - Running setup.py (path:/tmp/pip-boX60T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:44 INFO - Running setup.py (path:/tmp/pip-5Tm80l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:44 INFO - Running setup.py (path:/tmp/pip-qH7dR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:45 INFO - Running setup.py (path:/tmp/pip-wtZ1g5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:45 INFO - Running setup.py (path:/tmp/pip-k_s3Gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:45 INFO - Running setup.py (path:/tmp/pip-AfFSXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:41:45 INFO - Running setup.py install for manifestparser 08:41:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:41:45 INFO - Running setup.py install for mozcrash 08:41:45 INFO - Running setup.py install for mozdebug 08:41:45 INFO - Running setup.py install for mozdevice 08:41:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:41:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:41:45 INFO - Running setup.py install for mozfile 08:41:45 INFO - Running setup.py install for mozhttpd 08:41:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:41:45 INFO - Running setup.py install for mozinfo 08:41:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:41:45 INFO - Running setup.py install for mozInstall 08:41:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Running setup.py install for mozleak 08:41:46 INFO - Running setup.py install for mozlog 08:41:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Running setup.py install for moznetwork 08:41:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Running setup.py install for mozprocess 08:41:46 INFO - Running setup.py install for mozprofile 08:41:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Running setup.py install for mozrunner 08:41:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:41:46 INFO - Running setup.py install for mozscreenshot 08:41:46 INFO - Running setup.py install for moztest 08:41:46 INFO - Running setup.py install for mozversion 08:41:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:41:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:41:47 INFO - Cleaning up... 08:41:47 INFO - Return code: 0 08:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41: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:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:47 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:47 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:41:47 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:41:47 INFO - Using env: {'DISPLAY': ':0', 08:41:47 INFO - 'HOME': '/home/cltbld', 08:41:47 INFO - 'LANG': 'en_US.UTF-8', 08:41:47 INFO - 'LANGUAGE': 'en_US:en', 08:41:47 INFO - 'LOGNAME': 'cltbld', 08:41:47 INFO - 'MAIL': '/var/mail/cltbld', 08:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:47 INFO - 'MOZ_NO_REMOTE': '1', 08:41:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:47 INFO - 'NO_EM_RESTART': '1', 08:41:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:47 INFO - 'PWD': '/builds/slave/test', 08:41:47 INFO - 'SHELL': '/bin/bash', 08:41:47 INFO - 'SHLVL': '1', 08:41:47 INFO - 'TERM': 'linux', 08:41:47 INFO - 'TMOUT': '86400', 08:41:47 INFO - 'USER': 'cltbld', 08:41:47 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:47 INFO - '_': '/tools/buildbot/bin/python'} 08:41:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:47 INFO - Running setup.py (path:/tmp/pip-AoIAUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:47 INFO - Running setup.py (path:/tmp/pip-XzDkos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:47 INFO - Running setup.py (path:/tmp/pip-YRNwUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:47 INFO - Running setup.py (path:/tmp/pip-8HB2lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:47 INFO - Running setup.py (path:/tmp/pip-gqB0qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:47 INFO - Running setup.py (path:/tmp/pip-Hh67Fs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:47 INFO - Running setup.py (path:/tmp/pip-7SA9NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:47 INFO - Running setup.py (path:/tmp/pip-1B0W_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:47 INFO - Running setup.py (path:/tmp/pip-VbiQNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:47 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:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:47 INFO - Running setup.py (path:/tmp/pip-CMavvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:48 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:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:48 INFO - Running setup.py (path:/tmp/pip-xMoRXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:48 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:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:48 INFO - Running setup.py (path:/tmp/pip-XMmDFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:48 INFO - Running setup.py (path:/tmp/pip-i3eFo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:48 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:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:48 INFO - Running setup.py (path:/tmp/pip-TSYzVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:48 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:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:48 INFO - Running setup.py (path:/tmp/pip-VzKO95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:48 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:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:48 INFO - Running setup.py (path:/tmp/pip-yIa7jZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:48 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:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:48 INFO - Running setup.py (path:/tmp/pip-yw59OO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:48 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:41:48 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:41:48 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:41:48 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:41:48 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:41:48 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:41:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:41: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:41: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:41: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:41: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:41: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:41: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:41:50 INFO - Downloading blessings-1.6.tar.gz 08:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:41:50 INFO - Installing collected packages: blessings 08:41:50 INFO - Running setup.py install for blessings 08:41:50 INFO - Successfully installed blessings 08:41:50 INFO - Cleaning up... 08:41:50 INFO - Return code: 0 08:41:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:41:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:51 INFO - Reading from file tmpfile_stdout 08:41:51 INFO - Current package versions: 08:41:51 INFO - argparse == 1.2.1 08:41:51 INFO - blessings == 1.6 08:41:51 INFO - blobuploader == 1.2.4 08:41:51 INFO - docopt == 0.6.1 08:41:51 INFO - functools32 == 3.2.3-2 08:41:51 INFO - jsonschema == 2.5.1 08:41:51 INFO - manifestparser == 1.1 08:41:51 INFO - mozInstall == 1.12 08:41:51 INFO - mozcrash == 1.0 08:41:51 INFO - mozdebug == 0.1 08:41:51 INFO - mozdevice == 0.49 08:41:51 INFO - mozfile == 1.2 08:41:51 INFO - mozhttpd == 0.7 08:41:51 INFO - mozinfo == 0.9 08:41:51 INFO - mozleak == 0.1 08:41:51 INFO - mozlog == 3.4 08:41:51 INFO - moznetwork == 0.27 08:41:51 INFO - mozprocess == 0.24 08:41:51 INFO - mozprofile == 0.28 08:41:51 INFO - mozrunner == 6.13 08:41:51 INFO - mozscreenshot == 0.1 08:41:51 INFO - mozsystemmonitor == 0.3 08:41:51 INFO - moztest == 0.8 08:41:51 INFO - mozversion == 1.4 08:41:51 INFO - psutil == 3.1.1 08:41:51 INFO - requests == 1.2.3 08:41:51 INFO - wsgiref == 0.1.2 08:41:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41: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:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:51 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:51 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:41:51 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:41:51 INFO - Using env: {'DISPLAY': ':0', 08:41:51 INFO - 'HOME': '/home/cltbld', 08:41:51 INFO - 'LANG': 'en_US.UTF-8', 08:41:51 INFO - 'LANGUAGE': 'en_US:en', 08:41:51 INFO - 'LOGNAME': 'cltbld', 08:41:51 INFO - 'MAIL': '/var/mail/cltbld', 08:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:51 INFO - 'MOZ_NO_REMOTE': '1', 08:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:51 INFO - 'NO_EM_RESTART': '1', 08:41:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:51 INFO - 'PWD': '/builds/slave/test', 08:41:51 INFO - 'SHELL': '/bin/bash', 08:41:51 INFO - 'SHLVL': '1', 08:41:51 INFO - 'TERM': 'linux', 08:41:51 INFO - 'TMOUT': '86400', 08:41:51 INFO - 'USER': 'cltbld', 08:41:51 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:51 INFO - '_': '/tools/buildbot/bin/python'} 08:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 INFO - Cleaning up... 08:41:51 INFO - Return code: 0 08:41:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:41: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:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, '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': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', '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:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:41:51 INFO - Using env: {'DISPLAY': ':0', 08:41:51 INFO - 'HOME': '/home/cltbld', 08:41:51 INFO - 'LANG': 'en_US.UTF-8', 08:41:51 INFO - 'LANGUAGE': 'en_US:en', 08:41:51 INFO - 'LOGNAME': 'cltbld', 08:41:51 INFO - 'MAIL': '/var/mail/cltbld', 08:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:51 INFO - 'MOZ_NO_REMOTE': '1', 08:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:41:51 INFO - 'NO_EM_RESTART': '1', 08:41:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:51 INFO - 'PWD': '/builds/slave/test', 08:41:51 INFO - 'SHELL': '/bin/bash', 08:41:51 INFO - 'SHLVL': '1', 08:41:51 INFO - 'TERM': 'linux', 08:41:51 INFO - 'TMOUT': '86400', 08:41:51 INFO - 'USER': 'cltbld', 08:41:51 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:51 INFO - '_': '/tools/buildbot/bin/python'} 08:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:41:51 INFO - Cleaning up... 08:41:51 INFO - Return code: 0 08:41:51 INFO - Running post-action listener: _resource_record_post_action 08:41:51 INFO - Running post-action listener: _start_resource_monitoring 08:41:51 INFO - Starting resource monitoring. 08:41:51 INFO - [mozharness: 2017-02-16 16:41:51.518478Z] Finished create-virtualenv step (success) 08:41:51 INFO - [mozharness: 2017-02-16 16:41:51.518986Z] Running install step. 08:41:51 INFO - Running pre-action listener: _resource_record_pre_action 08:41:51 INFO - Running main action method: install 08:41:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:51 INFO - Reading from file tmpfile_stdout 08:41:51 INFO - Detecting whether we're running mozinstall >=1.0... 08:41:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:41:51 INFO - Reading from file tmpfile_stdout 08:41:51 INFO - Output received: 08:41:51 INFO - Usage: mozinstall [options] installer 08:41:51 INFO - Options: 08:41:51 INFO - -h, --help show this help message and exit 08:41:51 INFO - -d DEST, --destination=DEST 08:41:51 INFO - Directory to install application into. [default: 08:41:51 INFO - "/builds/slave/test"] 08:41:51 INFO - --app=APP Application being installed. [default: firefox] 08:41:51 INFO - mkdir: /builds/slave/test/build/application 08:41:51 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:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:42:08 INFO - Reading from file tmpfile_stdout 08:42:08 INFO - Output received: 08:42:08 INFO - /builds/slave/test/build/application/firefox/firefox 08:42:08 INFO - Running post-action listener: _resource_record_post_action 08:42:08 INFO - [mozharness: 2017-02-16 16:42:08.043716Z] Finished install step (success) 08:42:08 INFO - [mozharness: 2017-02-16 16:42:08.043840Z] Running run-tests step. 08:42:08 INFO - Running pre-action listener: _resource_record_pre_action 08:42:08 INFO - Running main action method: run_tests 08:42:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:42:08 INFO - Python 2.7.3 08:42:08 INFO - Return code: 0 08:42:08 INFO - grabbing minidump binary from tooltool 08:42:08 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:42:08 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 0x1b4b290>, '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 0x1b4bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:42:08 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:42:08 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:42:08 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:42:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:42:08 INFO - Return code: 0 08:42:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:42:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:42:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:42:08 INFO - ENV: RUST_BACKTRACE is now 1 08:42:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:42:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:42:08 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/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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:42:08 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/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --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:42:08 INFO - Using env: {'DISPLAY': ':0', 08:42:08 INFO - 'HOME': '/home/cltbld', 08:42:08 INFO - 'LANG': 'en_US.UTF-8', 08:42:08 INFO - 'LANGUAGE': 'en_US:en', 08:42:08 INFO - 'LOGNAME': 'cltbld', 08:42:08 INFO - 'MAIL': '/var/mail/cltbld', 08:42:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:42:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:08 INFO - 'MOZ_NO_REMOTE': '1', 08:42:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:08 INFO - 'NO_EM_RESTART': '1', 08:42:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:08 INFO - 'PWD': '/builds/slave/test', 08:42:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:42:08 INFO - 'RUST_BACKTRACE': '1', 08:42:08 INFO - 'SHELL': '/bin/bash', 08:42:08 INFO - 'SHLVL': '1', 08:42:08 INFO - 'TERM': 'linux', 08:42:08 INFO - 'TMOUT': '86400', 08:42:08 INFO - 'USER': 'cltbld', 08:42:08 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168', 08:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:08 INFO - '_': '/tools/buildbot/bin/python'} 08:42:08 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/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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:42:08 INFO - mozversion application_buildid: 20170216160436 08:42:08 INFO - mozversion application_changeset: 25929185c46777cf4e9eb3e9aad2f8a8a227178f 08:42:08 INFO - mozversion application_display_name: Nightly 08:42:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:42:08 INFO - mozversion application_name: Firefox 08:42:08 INFO - mozversion application_remotingname: firefox 08:42:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:42:08 INFO - mozversion application_vendor: Mozilla 08:42:08 INFO - mozversion application_version: 54.0a1 08:42:08 INFO - mozversion platform_buildid: 20170216160436 08:42:08 INFO - mozversion platform_changeset: 25929185c46777cf4e9eb3e9aad2f8a8a227178f 08:42:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:42:08 INFO - mozversion platform_version: 54.0a1 08:42:08 INFO - using testdate: 1487263328 08:42:08 INFO - actual date: 1487263328 08:42:08 INFO - starting webserver on 'localhost:58335' 08:42:08 INFO - SUITE-START | Running 2 tests 08:42:08 INFO - TEST-START | dromaeo_css 08:42:08 INFO - operating with platform_type : linux_ 08:42:08 INFO - Initialising browser for dromaeo_css test... 08:42:08 INFO - TEST-INFO | started process 3342 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp86Aze3/profile http://localhost:58335/getInfo.html) 08:42:12 INFO - PROCESS | 3342 | Iterating over profile /tmp/tmp86Aze3/profile 08:42:12 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:12 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/frequencyCap.json = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:12 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:12 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/thumbnails = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:12 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:12 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/places.sqlite-wal = Thu Feb 16 2017 08:42:12 GMT-0800 (PST) 08:42:12 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:12 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/places.sqlite = Thu Feb 16 2017 08:42:12 GMT-0800 (PST) 08:42:12 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:12 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/crashes = Thu Feb 16 2017 08:42:08 GMT-0800 (PST) 08:42:13 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:13 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/addons.json = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:13 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:13 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/localstore.rdf = Thu Feb 16 2017 08:41:22 GMT-0800 (PST) 08:42:13 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:13 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/key3.db = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:13 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:13 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/permissions.sqlite = Thu Feb 16 2017 08:42:10 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/gmp = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/places.sqlite-shm = Thu Feb 16 2017 08:42:10 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/directoryLinks.json = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/minidumps = Thu Feb 16 2017 08:42:08 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | Stat failure 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/prefs.js = Thu Feb 16 2017 08:41:22 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/pluginreg.dat = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/bookmarkbackups = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/cert8.db = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/content-prefs.sqlite = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/extensions = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/extensions.ini = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/extensions.json = Thu Feb 16 2017 08:42:11 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/user.js = Thu Feb 16 2017 08:42:08 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/.parentlock = Thu Feb 16 2017 08:42:08 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/secmod.db = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/cache2 = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/webappsstore.sqlite = Thu Feb 16 2017 08:42:12 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/mimeTypes.rdf = Thu Feb 16 2017 08:42:12 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/compatibility.ini = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/startupCache = Thu Feb 16 2017 08:42:08 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | Stat failure 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/safebrowsing = Thu Feb 16 2017 08:42:14 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/blocklist.xml = Thu Feb 16 2017 08:42:09 GMT-0800 (PST) 08:42:14 INFO - PROCESS | 3342 | No birth date. Using mtime. 08:42:14 INFO - PROCESS | 3342 | Using date: /tmp/tmp86Aze3/profile/sessionCheckpoints.json = Thu Feb 16 2017 08:42:12 GMT-0800 (PST) 08:42:15 INFO - PROCESS | 3342 | __metrics Screen width/height:1600/1200 08:42:15 INFO - PROCESS | 3342 | colorDepth:24 08:42:15 INFO - PROCESS | 3342 | Browser inner width/height: 1024/697 08:42:15 INFO - PROCESS | 3342 | __metrics 08:42:15 ERROR - PROCESS | 3342 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 08:42:16 INFO - TEST-INFO | 3342: exit 0 08:42:16 INFO - Browser initialized. 08:42:16 INFO - Running cycle 1/1 for dromaeo_css test... 08:42:16 INFO - TEST-INFO | started process 3461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp86Aze3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:28 INFO - PROCESS | 3461 | RSS: Main: 160251904 08:42:28 INFO - PROCESS | 3461 | 08:45:32 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58335/tests/dromaeo/cssquery-ext.html) 08:45:35 INFO - PROCESS | 3461 | RSS: Main: 172990464 08:45:35 INFO - PROCESS | 3461 | 08:47:17 INFO - PROCESS | 3461 | ************************* 08:47:17 INFO - PROCESS | 3461 | A coding exception was thrown and uncaught in a Task. 08:47:17 INFO - PROCESS | 3461 | 08:47:17 ERROR - PROCESS | 3461 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:47:17 INFO - PROCESS | 3461 | Full stack: 08:47:17 INFO - PROCESS | 3461 | ************************* 08:48:37 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-ext.html (next: http://localhost:58335/tests/dromaeo/cssquery-jquery.html) 08:48:38 INFO - PROCESS | 3461 | RSS: Main: 165937152 08:48:38 INFO - PROCESS | 3461 | 08:51:40 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58335/tests/dromaeo/cssquery-mootools.html) 08:51:41 INFO - PROCESS | 3461 | RSS: Main: 175001600 08:51:41 INFO - PROCESS | 3461 | 08:54:43 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58335/tests/dromaeo/cssquery-prototype.html) 08:54:43 INFO - PROCESS | 3461 | RSS: Main: 165498880 08:54:43 INFO - PROCESS | 3461 | 08:57:46 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58335/tests/dromaeo/cssquery-yui.html) 08:57:47 INFO - PROCESS | 3461 | RSS: Main: 170033152 08:57:47 INFO - PROCESS | 3461 | 09:00:49 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:58335/tests/dromaeo/cssquery-yui.html (next: http://localhost:58335/tests/dromaeo/cssquery-dojo.html) 09:00:50 INFO - PROCESS | 3461 | RSS: Main: 171319296 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | __start_tp_report 09:00:50 INFO - PROCESS | 3461 | _x_x_mozilla_page_load 09:00:50 INFO - PROCESS | 3461 | _x_x_mozilla_page_load_details 09:00:50 INFO - PROCESS | 3461 | |i|pagename|runs| 09:00:50 INFO - PROCESS | 3461 | |0;dojo.html;2610.3865591585977;2614.398688301689;2632.7498887605684;2639.274199595111;2639.7624213820754;4815.542523460274;4828.4205895292525;4851.2965619985125;4869.026194761049;4874;4768.999999999991;4783.186858234099;4829.541193586612;4892.266160075981;4938.678985865916;4173.908115460174;4419.447569053868;4516.796744146522;4519.638428925695;4540.636749060067;4492.371068050459;4492.730436173823;4501.189785838547;4507.594316511513;4527.071950250191;4447.955520444819;4471.731696098244;4485.977570112129;4494.752788596626;4528.9547104528865;4479.081788233404;4483.125790470859;4483.30508771141;4520.68355215135;4531.637469002472;4422.226110430694;4440.33394990754;4452.0205554777895;4468.374427580141;4505.121501307246;4752.904941901177;4755.0489902019735;4782.689125206885;4824.348712923746;4904.926426103611;5184.8963020739375;5190.584753219733;5228.215767634847;5245.082110630625;5279.44565820591;5287.629865909388;5291.047611429941;5294.126469132589;5313.123334649778;5333.919991200135;4764.6426518011285;4767.594754445911;4831.637627177975;4937.728424936663;4948.727819969864;5179.922301165486;5311.415744268127;5378.623496355296;5418.945810541923;5428.185772134211;4991.825286114969;5080.669756465818;5090.821821236239;5114.181730923035;5205.323307970016;5205.24523944026;5219.347581552306;5235.1623740201385;5237.842864713988;5240.371155461293;5090;5126.794928202905;5126.948730512647;5165.690058596496;5242.842714718565;5070.264811602297;5139.306193663882;5145.511176438307;5174.43081261061;5202.2456743771945;4363.454568178978;4377.0808130293;4397.692161548661;4412.69111162222;4423.292273236331;3783.281176576442;3807.9048023799073;3817.923641527154;3834.1373191031794;3851.5763266040713;4474.731516108979;4513.435820522434;4513.50351461339;4517.751523666164;4558.521355257716;5211.0359583477175;5212.478752124833;5214.2960700304975;5219.817306394258;5256.474352564713;4467.8212871484775;4515.932261016087;4522.954770452254;4523.434570678665;4565.246734288805;4598.8620341389815;4647.86056418308;4654.953450465519;4664.510226426244;4686.461056978423;4874.463808981081;4917.483664215206;4920.630952678492;4921.3356196912955;4931.7040977541465;5206;5213;5216.530512253916;5239.633225674166;5250.737463126905;4893.290472881484;4909.4354149273295;4920.827771027997;4926.064047830901;4926.137925862888;5092.0579692756955;5111.565516931102;5123.820666276662;5136.203888397305;5141.357330333708;5193.42872284056;5203.271541984201;5208.817691380784;5213.947860521346;5226.242194881797;5073.289739436408;5078.492150784892;5097.592192624507;5131.230315452712;5137.229415587692;5086.21163719624;5092.567131793691;5098.490150984871;5100.081985242692;5126.051680438982;4869.342638743866;4928.088303663833;4948.861761794736;4953.529414705597;4976.10430122567;5053.898922021613;5059.848204553869;5068.974655126701;5097.133487307093;5102.183650615883;4931.3589233399425;4937.950620493749;4938.308636790924;4945.307656927961;4974.602031837517 09:00:50 INFO - PROCESS | 3461 | |1;ext.html;526.3788729299426;542.823582335741;547.4142667345939;549.8845242499075;569.4220366328175;36231;38532.61467385329;38601.806990965044;38657.613423865754;38703.61296387035;39740.60259397405;40088.59911400885;40116.39825402614;40210.19579608406;40254;7625.542467451943;7785.182555831641;7805;7816.726414575491;9354.906450935472;9204.493752843624;9361.625534978626;9478.43129412234;9522.143007129356;9546.665866694668;9475.289353298496;9527.285453590974;9539.332246742732;9543.236541076732;9559.330846840723;9496.477693726842;9510.191633711109;9515.619375224982;9573.329866909318;9574.23406127515;9410.247180225633;9452.95273523628;9460.148586627167;9460.24318054554;9466.857997130048;33658.1585460363;33963.490547641806;33964;33969;34041.319173616386;3306.6528014558367;3448.8448019839166;3466.722662187044;3468.9132771680656;3488.2151515908718;25712.742872571223;25828.483430331475;25860;26189.73810261892;26210.606840897402;3300.488424294238;3321.983390083058;3367.3097015111844;3410.2838403934957;3489.3370259650587;228987;229930;231010;231388;231583;39866;41392.58607413948;47024;47141.764291178326;47449.76275118603;61028;61256.69371653113;66120.66939665271;66632.6668366655;67183.66408167928;13038.608841734764;13343.66640833987;13742.725145497034;13746.381412836447;13751.243681597609;9724.61101555944;9741.853872191923;9794.706158815246;9817.55820988043;9857.55640996157;18957.957312347953;19014.524636884187;19138.330158444387;19206.61586768257;19412.902935485232;6943.583384996917;7493.550386976799;7530.653236202248;7579.1284002339335;7659.540427574363;768.8927943760966;770.4722265248323;797.0826774007106;815.8205194857122;817.627979269431;27468;28361.716382835908;28479.430411391655;28646;28799.712002879704;892.3084609427616;905.7871400220943;924.6393906376519;941.3692825806589;946.2666433514082;14169.574912752634;14641.194734289717;14730.189839558712;14877.33052012662;14884.62788430298;331981;332036;333987;334737;337280;49531;50040.749796250784;50152.498475014785;50156;50221.49778502241;1128.8137457319451;1174.8942595166477;1176.817593273044;1184.8992835608897;1191.9880801191878;25335.36661583475;26402.471950560495;26630.20109396721;27197.320066998484;27305.180844574694;41420.75737727873;41502.792486037375;41780.791096044326;41792.16415671731;41848.37227441652;273836;274617;274690;275234;275712;29792.553111703775;30499.695003049685;35009.47485787766;36578.8171059143;37304.813475932446;36923.630763693094;37458.2508349826;38052.42921356237;38224.23551529009;38578.228435431694;32389.838050809594;32976.505352420216;33325.33349333048;33611.83194084014;33629.83185084059;32301;32732.509012364357;33910;33943.83028084844;34779.65220347764;32252.67747322497;32543.674563254066;32593;33093.66906330906;33483.330333393686;28094.066830643318;28434;29247.7075229245;29341.706582933897;30628.387432251675;23147.305580832603;23452.64821027652;23458.413539661644;23459.296221113393;23682.763172368057 09:00:50 INFO - PROCESS | 3461 | |2;jquery.html;395.8634271176445;404.9412835138903;408.3629537920843;416.5834165834166;428.58213242088965;17167.828321716777;17644.206010729515;17928.910355448206;17973.730394044083;18925.242990280407;10784.892151078488;10991.835122473149;11033.448327583608;16151.43469978551;17630.11849407531;4611.100835337103;4773.451053128882;4780.235162374021;4786.521347865203;4794.089123066624;4550.453945526541;4584.083183363324;4635.745034023127;4642.117997580466;4645.163870503308;4651.767411629422;4657.464391594959;4666.183417901871;4680.625549955996;4700.412448443944;4492.97753511232;4642.535746425364;4653.88365290867;4657.161710892038;4688.7186768793645;4586.449626044896;4589.770511474447;4589.908201835945;4592.724436533786;4596.655250856165;11981.700457488545;13128.934355328258;21765.45586360338;22166.889165554232;22195.556088878293;2413.9861258271394;2424.248482970285;2424.8181386395913;2462.9014839406404;2530.5571524983056;8983.146601072887;9074.63701451941;14890.404383824634;14984.325705343177;15454.459093931771;1839.9448016559525;1856.3097158456385;1863.431653345729;1878.624275144963;1882.105999650169;87689.56155219184;91984.08015919889;93682.53158734163;96690.9999999993;97537;17938.820611793977;18283.72574411385;18292.17685204169;18389.81610183881;18406.539836504195;33199.83400082984;33462.16344591405;33490.83254583712;33524.1618959528;34026.829865850515;8763.518006509703;8792.648294068165;8856.424332418372;8931.910680893108;9130.08699130095;7060.682269297894;7101.325374089453;7266.164391094986;7331.963340183265;7344.742933997284;9285.928519099358;9320.30097742672;9456.33805633599;9463.59942626682;9530.809383812424;3471.1810554535273;3634.67287944081;3922.7254092213843;3950.7036972227197;4044.7168698190844;801.2028032107974;810.7973006748313;817.8159914019417;828.3704384667695;850.9787255318543;16836.24236909342;16968.40610578624;16974;17108.315667373416;17889;802.8594995875699;820.6799348254185;825.3603457320625;834.3408707121306;836.4145098431122;9249;9498;9855.80288394228;9888.950555247178;10039.648612298535;468082;477318;479032;481192;493176;65354.346456534826;79691;89341;89543.55228223947;90058;963.2342287881152;975.2539307429822;998.2313618513704;998.4558415663481;1003.94478303694;14884.702305954037;15027.398904043715;16187.595310117342;17412.303507859542;17868.642627147645;40104.598954010085;40985.590144098176;41042.79478602588;41830.79084604557;41861;49976.500234997184;51358.743206285224;53161;53915.730421347645;54407.18389224244;7380.815479613053;7525.661345239587;7747.457677962489;7802.960985195038;7987.201279871966;9044.909550904407;11707.2975621463;11870.82193767077;12191.634250972485;12291.078169137529;9148.954255228948;9214.40106393109;9295.86056209171;9404.529773511435;9678.370905891376;9937;10346.37921724699;10532.947335263274;10629.681109566725;11045.779084418427;9209.309301802257;9220.631174752934;9223.216026637543;9754.9024509754;9905.306628536222;7400.148982866824;7495.775126746206;7780.805479863048;7795.610219489116;7830.80422989407;9359.906400936175;9691.612335506783;9999.55002024896;9999.800004000024;10430.25031208191 09:00:50 INFO - PROCESS | 3461 | |3;mootools.html;1329.6011196641005;1335.5459143891069;1336.024701967563;1337.6187786480853;1341.0344551922615;7200.991861139438;7357.0803649543805;7511.286427789351;7628.580428076453;7650.234976502347;7396.186419493852;7497.550146991171;7503.898908305683;7509.7747067587925;7596.810079748009;2114;2141.946451338721;2152.1283880028614;2156.0836644426136;2156.590247852903;2102.9579408411823;2146.3453646637768;2161.384005558416;2161.7730138335482;2169.4467910682833;2089.1434511850143;2107.388857231401;2118.724565806443;2132.6694362373855;2135.135270215565;2089.550746589483;2105.6420408530585;2107.8524503284775;2108.673155660875;2114.8202402795705;1789.2216885654782;1840.429466865276;2035.735354403933;2037.9388618341472;2123.0870725587993;7410.777676669709;7503.849923001564;7684.077910650702;7684.308412242869;7703.805910083946;4660.277656963176;4814.879628009293;4820.6143508519235;4823.252395878644;4825.806967721322;2840.573913912909;2913.0678182981455;2943.45546073977;2953.675095739467;2966.6143401357685;1171.3733152763316;1199.1905463811893;1199.2624535910352;1204.4098391788048;1206.0652993929687;10259.79480410395;10394.376337419704;10447.634332798392;10477.371357718484;10478.10936070435;4027.194561087794;4253.659707223414;4324.870253892388;4328.675349348811;4332.263515202423;8900.198982091642;8949.418287811402;9006.054364291786;9038.909610903938;9041.095890411038;4793.712377257306;4823.397075365579;4828.686135401188;4836.177849765549;4854.68444551103;4236.491621005499;4292.463442069741;4300.440942677495;4389.407429996973;4392.450943632046;3625.4743062255825;3680.742348035612;3744.737868349244;3751.456038874348;3769.5288088988877;1239.5971309324505;1245.2777389114292;1249.0694432647736;1257.9433925473375;1272.5609664665676;691.325957191734;707.6320313436984;719.9424046076301;730.8794048981858;747.0698979770112;4433.202023635712;4445.866624001285;4489.416375871181;4496.258117410656;4599.1721490132095;476.31172955079575;480.075853981085;490.2156549520738;492.4140273075029;498.61606562946326;2855.500287449688;2872.6409198850142;2944.2344990302677;2948.5871977923107;2960.985195074054;8127.512349259064;8518.914810851813;8541;8730.607122679494;8968.417052891542;9071.81856362869;9113.179813816789;9137.451752894847;9143.13140251675;9176.632934682671;6907.13660792401;7161.104861892263;7217.530860494051;7244.818879527948;7268.418526517866;4360.324149756792;4401.537838526909;4404.757738324423;4430.512643609265;4498.460184777848;8189.262966332821;8345.165483451607;8367.790805229917;8377.204165604258;8441.480533504027;8381.203785640355;8400.579971001303;8470.068292487944;8476.449030812977;8672.913270867211;6116.357782432959;6163.876722465615;6187.721552530237;6194.876102478015;6222.595531290452;6868.210155831944;6903.413209877217;6906.585604863724;6955.339242771928;6966.407855332353;6477.4170324671;6485.481161506974;6531.706073226811;6554.049662798772;6643.335666433318;6306.495480361468;6310.368963103653;6327.303996560283;6357.841053973688;6358.93641063602;5344.305240318734;5359.785608575769;5361.812336568201;5393.541548968382;5397.190421436659;3887.300285948443;3935.0162459385156;4005.4592629995745;4044.3326851069246;4111.958880411157;4130.648894843972;4136.8003279048735;4239.576042395735;4278.165757677223;4332.068605249887 09:00:50 INFO - PROCESS | 3461 | |4;prototype.html;353.83900325351965;356.5489655585684;360.79795314623794;367.24714335612003;370.0305200375018;5101.10730622141;5114.488551144884;5115.488451154883;5122.180451127821;5130.358705161855;5029.899402011957;5043.218301163316;5051.040302342552;5063;5066.74666266687;3744.3073031489193;3872.224581033484;3886.533615966088;3890.513685789262;3927.5286965564173;3872.9419058714143;3886.650201481866;3899.181171953893;3915.7846318452475;3922.7057970652313;3840.788756618385;3858;3867.9226415471676;3873.573906870242;3875.934118117512;3630.2920930418577;3834.616538346171;3856.2094770572107;3878.26313000529;3882.0683036071423;3371.0561042908025;3388.220709236865;3403.9829800851085;3408.568401271472;3427.845746941394;4938.9012219755405;4947.158982972904;4956.975215123901;4957.107720610289;4961.131801934647;4474.2393793054935;4495.57292057254;4495.887602809923;4522.163399771053;4535.659825513099;4969.478204788517;5149.407818100892;5227.764750586232;5232.2674825524455;5232.424433312332;3692.316921369555;3701.7223708221723;3715.851365945359;3722.9813850930573;3736.7384283100196;5307.815310813519;5489.560835133179;5517.337919449652;5551.472610102034;5568.109102543614;3717.1450566369886;3748.7188460865545;3754.1553150541454;3772.396416573335;3777.58446570877;3786.9431958520645;3822.025383527183;3825.2923209206388;3838.098046958962;3848.5381754189675;4384.824607015684;4405.647548196201;4412.61002784122;4425.889352766207;4436.183023779923;5061.747522352518;5268.789248430096;5277.050130976461;5286.920696189561;5300.390455097636;1300.6878349196124;1311.0363882546321;1317.5638553976212;1317.9802302965463;1324.5231716582023;841.7769291137731;889.6886089868494;891.4562117108559;899.5771987166153;907.9092090790868;4475.328700694857;4484.215262329079;4490.418009644468;4491.955080449219;4493.213687604656;5253.212018197224;5278.683279003272;5291.391489978701;5317.8404647860625;5326.36083669962;4306.89232769177;4505.391772110723;4552.565941728345;4556.476005259371;4559.566841150086;2799.5380762174013;2800.28592708862;2805.298675331167;2810.7048759880327;2836.886524538995;4956.430010548761;4963.60291176705;4964.726940018261;4990.226514890198;4993.177490216118;5113.826623684993;5381.973090134603;5428.538574221236;5436.238926550286;5440.727963601804;4981.726005069756;4986.1551226046595;4988.127077761377;5004.974975125101;5041.823536176216;5184.559312458483;5263.710495922761;5274.815381461631;5285.339332583427;5340.893182136258;4896.947156361257;5122.974385128051;5144.6398752087;5153.948460515347;5230.790768369375;4906.803727850846;4929.672471082077;4933.284673722362;4980.651354405157;5072.85483000542;4070.3080476318505;4104.158647477319;4140.296149654506;4143.274926887722;4163.8334466621;5486.506214440719;5513.531349835309;5518.972405137949;5520.254765606753;5526.696031718133;5167.328247327973;5196.948030519798;5222.190560463135;5298.873664537941;5353.384360798403;5225.503577160163;5266.183741520071;5304.7347632618985;5331.466853314638;5342.706151161723;5231.476852314738;5247.711375874211;5270.578353731616;5273.31446911899;5304.9469505306;5103.617228707788;5288.312519372457;5303.549198318032;5354.732263386893;5355.544778693855;5182.352205974254;5199.7140157291715;5204.646791834075;5217.869553261047;5229.111051121243 09:00:50 INFO - PROCESS | 3461 | |5;yui.html;609.9085137229413;645.6932956845496;647.1845474701875;659.9604023758575;666.4368608525795;12503.749925001506;13248.47006119754;13428.798568021475;13463;14248.28758562073;12616.243025418458;12791.50886984254;12908.741825163492;12933.288669123194;12964.092513524058;788.2117882117868;800.9199080092003;810.0481933727875;810.1574362662824;814.1085511365076;796.3231253434564;811.1726039439768;820.9425340226187;827.9668813247463;831.3141658132035;843.7510934274404;848.7156802471179;849.5539841583187;851.2849206666399;852.7484392104313;841.0202114536557;842.2924743215729;843.2747836860295;843.4011851585382;847.8982522097357;622.9968850155766;623.3081279779441;624.1792043462832;625.8560531077832;626.2203556572051;13251.403686834115;13477.191368517822;13503.797443038362;13508.324583770773;14053;11948;12124.393780311037;12135.271883686919;12282.078844086729;12361.814572781415;9946.162788957123;9991.800163996752;10073.496325183785;10107.292489525737;10139.290249682525;3016.095171448557;3092.118746157357;3103.239706271978;3184.4268031754277;3185.697358750915;61267.693661531855;61282;61786.69106654483;62318.376816231714;62372.68813655948;10003.499825008721;10072.496375181358;10172.491375431198;10285.640002599874;10314.123299519624;19181.80818191828;19239.422817315502;19269.325573605136;19375.996720508345;19381.321653742052;5589.832305030855;5604.551635869121;5674.319081710221;5823.737931793079;5853.502452291517;3168.413843438925;3247.691469310412;3280.4423248047888;3296.225387034045;3303.1969800481447;3105.7981231219896;3232.563603913489;3238.5789847319697;3239.5788547489146;3241.589908389858;512.7564406906689;513.4147072337538;523.4268476018754;523.9030779305841;582.128843192763;560.1485741672635;564.9378568357478;584.1354794903566;601.1913975702673;617.783775678509;8950;8960.238379737664;9006.954965225132;9406.623735050522;9424.811503769886;471.4920987850382;474.08738179005275;481.6147082334119;482.10088185533726;487.8902246994469;4114.053767633402;4220.873373798791;4287.485501739811;4326.675499337499;4392.802323895432;62197.689011554656;63057.68471157615;64162;65624;66356;58820;59407.10889336663;59477.70261148753;59490.70254648699;59740;49977;50095;51726.74136629368;51882.22176667353;52000;8042.396820238622;8291;8410.6215220314;8487.36344774132;8576.142385761374;29574.556381653863;30199.849000754857;30273.848630756704;33167.502487462225;36600;40339.596604033584;42114;43116.35325470183;43511;44300.55699443093;10079.143272821892;10124.848127278245;10260.486975651336;10423.791524169323;10779.461026948778;12408;12682;12792.232466052066;12987.610371688865;13434.529791457253;11688;11912.344821034927;12096.758064838477;12269.87730122723;12697.682557936125;11539.249948753304;11812.586559470377;11845.703857403634;11957.641270761891;12131.029517638392;11219.270747401393;11820.82268765952;11873.821892671443;11879.346635935106;12218.327991960528;9409.623615055594;9512.310253161468;9641.855372169282;9757.316987810786;9986;7793.532388056735;8762.342824288331;8839.823203536022;9128.224100951493;9185.127412895765 09:00:50 INFO - PROCESS | 3461 | __end_tp_report 09:00:50 INFO - PROCESS | 3461 | __start_cc_report 09:00:50 INFO - PROCESS | 3461 | _x_x_mozilla_cycle_collect,357 09:00:50 INFO - PROCESS | 3461 | __end_cc_report 09:00:50 INFO - PROCESS | 3461 | __startTimestamp1487264450130__endTimestamp 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | ------- Summary: start ------- 09:00:50 INFO - PROCESS | 3461 | Number of tests: 6 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | [#0] dojo.html Cycles:165 Average:4803.44 Median:4928.09 stddev:518.68 (10.5%) stddev-sans-first:490.91 09:00:50 INFO - PROCESS | 3461 | Values: 2610.4 2614.4 2632.7 2639.3 2639.8 4815.5 4828.4 4851.3 4869.0 4874.0 4769.0 4783.2 4829.5 4892.3 4938.7 4173.9 4419.4 4516.8 4519.6 4540.6 4492.4 4492.7 4501.2 4507.6 4527.1 4448.0 4471.7 4486.0 4494.8 4529.0 4479.1 4483.1 4483.3 4520.7 4531.6 4422.2 4440.3 4452.0 4468.4 4505.1 4752.9 4755.0 4782.7 4824.3 4904.9 5184.9 5190.6 5228.2 5245.1 5279.4 5287.6 5291.0 5294.1 5313.1 5333.9 4764.6 4767.6 4831.6 4937.7 4948.7 5179.9 5311.4 5378.6 5418.9 5428.2 4991.8 5080.7 5090.8 5114.2 5205.3 5205.2 5219.3 5235.2 5237.8 5240.4 5090.0 5126.8 5126.9 5165.7 5242.8 5070.3 5139.3 5145.5 5174.4 5202.2 4363.5 4377.1 4397.7 4412.7 4423.3 3783.3 3807.9 3817.9 3834.1 3851.6 4474.7 4513.4 4513.5 4517.8 4558.5 5211.0 5212.5 5214.3 5219.8 5256.5 4467.8 4515.9 4523.0 4523.4 4565.2 4598.9 4647.9 4655.0 4664.5 4686.5 4874.5 4917.5 4920.6 4921.3 4931.7 5206.0 5213.0 5216.5 5239.6 5250.7 4893.3 4909.4 4920.8 4926.1 4926.1 5092.1 5111.6 5123.8 5136.2 5141.4 5193.4 5203.3 5208.8 5213.9 5226.2 5073.3 5078.5 5097.6 5131.2 5137.2 5086.2 5092.6 5098.5 5100.1 5126.1 4869.3 4928.1 4948.9 4953.5 4976.1 5053.9 5059.8 5069.0 5097.1 5102.2 4931.4 4938.0 4938.3 4945.3 4974.6 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | [#1] ext.html Cycles:180 Average:43826.40 Median:3488.78 stddev:74097.99 (2123.9%) stddev-sans-first:74234.53 09:00:50 INFO - PROCESS | 3461 | Values: 526.4 542.8 547.4 549.9 569.4 36231.0 38532.6 38601.8 38657.6 38703.6 39740.6 40088.6 40116.4 40210.2 40254.0 7625.5 7785.2 7805.0 7816.7 9354.9 9204.5 9361.6 9478.4 9522.1 9546.7 9475.3 9527.3 9539.3 9543.2 9559.3 9496.5 9510.2 9515.6 9573.3 9574.2 9410.2 9453.0 9460.1 9460.2 9466.9 33658.2 33963.5 33964.0 33969.0 34041.3 3306.7 3448.8 3466.7 3468.9 3488.2 25712.7 25828.5 25860.0 26189.7 26210.6 3300.5 3322.0 3367.3 3410.3 3489.3 228987.0 229930.0 231010.0 231388.0 231583.0 39866.0 41392.6 47024.0 47141.8 47449.8 61028.0 61256.7 66120.7 66632.7 67183.7 13038.6 13343.7 13742.7 13746.4 13751.2 9724.6 9741.9 9794.7 9817.6 9857.6 18958.0 19014.5 19138.3 19206.6 19412.9 6943.6 7493.6 7530.7 7579.1 7659.5 768.9 770.5 797.1 815.8 817.6 27468.0 28361.7 28479.4 28646.0 28799.7 892.3 905.8 924.6 941.4 946.3 14169.6 14641.2 14730.2 14877.3 14884.6 331981.0 332036.0 333987.0 334737.0 337280.0 49531.0 50040.7 50152.5 50156.0 50221.5 1128.8 1174.9 1176.8 1184.9 1192.0 25335.4 26402.5 26630.2 27197.3 27305.2 41420.8 41502.8 41780.8 41792.2 41848.4 273836.0 274617.0 274690.0 275234.0 275712.0 29792.6 30499.7 35009.5 36578.8 37304.8 36923.6 37458.3 38052.4 38224.2 38578.2 32389.8 32976.5 33325.3 33611.8 33629.8 32301.0 32732.5 33910.0 33943.8 34779.7 32252.7 32543.7 32593.0 33093.7 33483.3 28094.1 28434.0 29247.7 29341.7 30628.4 23147.3 23452.6 23458.4 23459.3 23682.8 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | [#2] jquery.html Cycles:180 Average:28689.82 Median:4661.82 stddev:79260.47 (1700.2%) stddev-sans-first:79454.35 09:00:50 INFO - PROCESS | 3461 | Values: 395.9 404.9 408.4 416.6 428.6 17167.8 17644.2 17928.9 17973.7 18925.2 10784.9 10991.8 11033.4 16151.4 17630.1 4611.1 4773.5 4780.2 4786.5 4794.1 4550.5 4584.1 4635.7 4642.1 4645.2 4651.8 4657.5 4666.2 4680.6 4700.4 4493.0 4642.5 4653.9 4657.2 4688.7 4586.4 4589.8 4589.9 4592.7 4596.7 11981.7 13128.9 21765.5 22166.9 22195.6 2414.0 2424.2 2424.8 2462.9 2530.6 8983.1 9074.6 14890.4 14984.3 15454.5 1839.9 1856.3 1863.4 1878.6 1882.1 87689.6 91984.1 93682.5 96691.0 97537.0 17938.8 18283.7 18292.2 18389.8 18406.5 33199.8 33462.2 33490.8 33524.2 34026.8 8763.5 8792.6 8856.4 8931.9 9130.1 7060.7 7101.3 7266.2 7332.0 7344.7 9285.9 9320.3 9456.3 9463.6 9530.8 3471.2 3634.7 3922.7 3950.7 4044.7 801.2 810.8 817.8 828.4 851.0 16836.2 16968.4 16974.0 17108.3 17889.0 802.9 820.7 825.4 834.3 836.4 9249.0 9498.0 9855.8 9889.0 10039.6 468082.0 477318.0 479032.0 481192.0 493176.0 65354.3 79691.0 89341.0 89543.6 90058.0 963.2 975.3 998.2 998.5 1003.9 14884.7 15027.4 16187.6 17412.3 17868.6 40104.6 40985.6 41042.8 41830.8 41861.0 49976.5 51358.7 53161.0 53915.7 54407.2 7380.8 7525.7 7747.5 7803.0 7987.2 9044.9 11707.3 11870.8 12191.6 12291.1 9149.0 9214.4 9295.9 9404.5 9678.4 9937.0 10346.4 10532.9 10629.7 11045.8 9209.3 9220.6 9223.2 9754.9 9905.3 7400.1 7495.8 7780.8 7795.6 7830.8 9359.9 9691.6 9999.6 9999.8 10430.3 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | [#3] mootools.html Cycles:180 Average:4870.70 Median:2568.29 stddev:2733.44 (106.4%) stddev-sans-first:2728.16 09:00:50 INFO - PROCESS | 3461 | Values: 1329.6 1335.5 1336.0 1337.6 1341.0 7201.0 7357.1 7511.3 7628.6 7650.2 7396.2 7497.6 7503.9 7509.8 7596.8 2114.0 2141.9 2152.1 2156.1 2156.6 2103.0 2146.3 2161.4 2161.8 2169.4 2089.1 2107.4 2118.7 2132.7 2135.1 2089.6 2105.6 2107.9 2108.7 2114.8 1789.2 1840.4 2035.7 2037.9 2123.1 7410.8 7503.8 7684.1 7684.3 7703.8 4660.3 4814.9 4820.6 4823.3 4825.8 2840.6 2913.1 2943.5 2953.7 2966.6 1171.4 1199.2 1199.3 1204.4 1206.1 10259.8 10394.4 10447.6 10477.4 10478.1 4027.2 4253.7 4324.9 4328.7 4332.3 8900.2 8949.4 9006.1 9038.9 9041.1 4793.7 4823.4 4828.7 4836.2 4854.7 4236.5 4292.5 4300.4 4389.4 4392.5 3625.5 3680.7 3744.7 3751.5 3769.5 1239.6 1245.3 1249.1 1257.9 1272.6 691.3 707.6 719.9 730.9 747.1 4433.2 4445.9 4489.4 4496.3 4599.2 476.3 480.1 490.2 492.4 498.6 2855.5 2872.6 2944.2 2948.6 2961.0 8127.5 8518.9 8541.0 8730.6 8968.4 9071.8 9113.2 9137.5 9143.1 9176.6 6907.1 7161.1 7217.5 7244.8 7268.4 4360.3 4401.5 4404.8 4430.5 4498.5 8189.3 8345.2 8367.8 8377.2 8441.5 8381.2 8400.6 8470.1 8476.4 8672.9 6116.4 6163.9 6187.7 6194.9 6222.6 6868.2 6903.4 6906.6 6955.3 6966.4 6477.4 6485.5 6531.7 6554.0 6643.3 6306.5 6310.4 6327.3 6357.8 6358.9 5344.3 5359.8 5361.8 5393.5 5397.2 3887.3 3935.0 4005.5 4044.3 4112.0 4130.6 4136.8 4239.6 4278.2 4332.1 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | [#4] prototype.html Cycles:180 Average:4342.28 Median:4964.16 stddev:1259.73 (25.4%) stddev-sans-first:1227.17 09:00:50 INFO - PROCESS | 3461 | Values: 353.8 356.5 360.8 367.2 370.0 5101.1 5114.5 5115.5 5122.2 5130.4 5029.9 5043.2 5051.0 5063.0 5066.7 3744.3 3872.2 3886.5 3890.5 3927.5 3872.9 3886.7 3899.2 3915.8 3922.7 3840.8 3858.0 3867.9 3873.6 3875.9 3630.3 3834.6 3856.2 3878.3 3882.1 3371.1 3388.2 3404.0 3408.6 3427.8 4938.9 4947.2 4957.0 4957.1 4961.1 4474.2 4495.6 4495.9 4522.2 4535.7 4969.5 5149.4 5227.8 5232.3 5232.4 3692.3 3701.7 3715.9 3723.0 3736.7 5307.8 5489.6 5517.3 5551.5 5568.1 3717.1 3748.7 3754.2 3772.4 3777.6 3786.9 3822.0 3825.3 3838.1 3848.5 4384.8 4405.6 4412.6 4425.9 4436.2 5061.7 5268.8 5277.1 5286.9 5300.4 1300.7 1311.0 1317.6 1318.0 1324.5 841.8 889.7 891.5 899.6 907.9 4475.3 4484.2 4490.4 4492.0 4493.2 5253.2 5278.7 5291.4 5317.8 5326.4 4306.9 4505.4 4552.6 4556.5 4559.6 2799.5 2800.3 2805.3 2810.7 2836.9 4956.4 4963.6 4964.7 4990.2 4993.2 5113.8 5382.0 5428.5 5436.2 5440.7 4981.7 4986.2 4988.1 5005.0 5041.8 5184.6 5263.7 5274.8 5285.3 5340.9 4896.9 5123.0 5144.6 5153.9 5230.8 4906.8 4929.7 4933.3 4980.7 5072.9 4070.3 4104.2 4140.3 4143.3 4163.8 5486.5 5513.5 5519.0 5520.3 5526.7 5167.3 5196.9 5222.2 5298.9 5353.4 5225.5 5266.2 5304.7 5331.5 5342.7 5231.5 5247.7 5270.6 5273.3 5304.9 5103.6 5288.3 5303.5 5354.7 5355.5 5182.4 5199.7 5204.6 5217.9 5229.1 09:00:50 INFO - PROCESS | 3461 | 09:00:50 INFO - PROCESS | 3461 | [#5] yui.html Cycles:180 Average:14537.68 Median:481.86 stddev:18080.05 (3752.2%) stddev-sans-first:18100.52 09:00:50 INFO - PROCESS | 3461 | Values: 609.9 645.7 647.2 660.0 666.4 12503.7 13248.5 13428.8 13463.0 14248.3 12616.2 12791.5 12908.7 12933.3 12964.1 788.2 800.9 810.0 810.2 814.1 796.3 811.2 820.9 828.0 831.3 843.8 848.7 849.6 851.3 852.7 841.0 842.3 843.3 843.4 847.9 623.0 623.3 624.2 625.9 626.2 13251.4 13477.2 13503.8 13508.3 14053.0 11948.0 12124.4 12135.3 12282.1 12361.8 9946.2 9991.8 10073.5 10107.3 10139.3 3016.1 3092.1 3103.2 3184.4 3185.7 61267.7 61282.0 61786.7 62318.4 62372.7 10003.5 10072.5 10172.5 10285.6 10314.1 19181.8 19239.4 19269.3 19376.0 19381.3 5589.8 5604.6 5674.3 5823.7 5853.5 3168.4 3247.7 3280.4 3296.2 3303.2 3105.8 3232.6 3238.6 3239.6 3241.6 512.8 513.4 523.4 523.9 582.1 560.1 564.9 584.1 601.2 617.8 8950.0 8960.2 9007.0 9406.6 9424.8 471.5 474.1 481.6 482.1 487.9 4114.1 4220.9 4287.5 4326.7 4392.8 62197.7 63057.7 64162.0 65624.0 66356.0 58820.0 59407.1 59477.7 59490.7 59740.0 49977.0 50095.0 51726.7 51882.2 52000.0 8042.4 8291.0 8410.6 8487.4 8576.1 29574.6 30199.8 30273.8 33167.5 36600.0 40339.6 42114.0 43116.4 43511.0 44300.6 10079.1 10124.8 10260.5 10423.8 10779.5 12408.0 12682.0 12792.2 12987.6 13434.5 11688.0 11912.3 12096.8 12269.9 12697.7 11539.2 11812.6 11845.7 11957.6 12131.0 11219.3 11820.8 11873.8 11879.3 12218.3 9409.6 9512.3 9641.9 9757.3 9986.0 7793.5 8762.3 8839.8 9128.2 9185.1 09:00:50 INFO - PROCESS | 3461 | -------- Summary: end -------- 09:00:50 INFO - PROCESS | 3461 | 09:00:51 INFO - TEST-INFO | 3461: exit 0 09:00:51 INFO - TEST-OK | dromaeo_css | took 1122385ms 09:00:51 INFO - TEST-START | kraken 09:00:51 INFO - operating with platform_type : linux_ 09:00:51 INFO - Initialising browser for kraken test... 09:00:51 INFO - TEST-INFO | started process 3813 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Zubao/profile http://localhost:58335/getInfo.html) 09:00:54 INFO - PROCESS | 3813 | Iterating over profile /tmp/tmp_Zubao/profile 09:00:54 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:54 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/frequencyCap.json = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/thumbnails = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/places.sqlite-wal = Thu Feb 16 2017 09:00:55 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/places.sqlite = Thu Feb 16 2017 09:00:54 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/crashes = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/addons.json = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | __metrics Screen width/height:1600/1200 09:00:55 INFO - PROCESS | 3813 | colorDepth:24 09:00:55 INFO - PROCESS | 3813 | Browser inner width/height: 1024/697 09:00:55 INFO - PROCESS | 3813 | __metrics 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/localstore.rdf = Thu Feb 16 2017 08:41:22 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/key3.db = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/permissions.sqlite = Thu Feb 16 2017 09:00:52 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/gmp = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/places.sqlite-shm = Thu Feb 16 2017 09:00:52 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/directoryLinks.json = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/minidumps = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/prefs.js = Thu Feb 16 2017 08:41:22 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/pluginreg.dat = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/bookmarkbackups = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/cert8.db = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/content-prefs.sqlite = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/extensions = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/extensions.ini = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/extensions.json = Thu Feb 16 2017 09:00:53 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/user.js = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/.parentlock = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/secmod.db = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/cache2 = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/compatibility.ini = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/startupCache = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | Stat failure 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/safebrowsing = Thu Feb 16 2017 09:00:55 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/blocklist.xml = Thu Feb 16 2017 09:00:51 GMT-0800 (PST) 09:00:55 INFO - PROCESS | 3813 | No birth date. Using mtime. 09:00:55 INFO - PROCESS | 3813 | Using date: /tmp/tmp_Zubao/profile/sessionCheckpoints.json = Thu Feb 16 2017 09:00:54 GMT-0800 (PST) 09:00:56 ERROR - PROCESS | 3813 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 09:00:57 INFO - TEST-INFO | 3813: exit 0 09:00:57 INFO - Browser initialized. 09:00:57 INFO - Running cycle 1/1 for kraken test... 09:00:57 INFO - TEST-INFO | started process 3933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Zubao/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:09 INFO - PROCESS | 3933 | RSS: Main: 160628736 09:01:09 INFO - PROCESS | 3933 | 09:01:12 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-beat-detection) 09:01:13 INFO - PROCESS | 3933 | RSS: Main: 166768640 09:01:13 INFO - PROCESS | 3933 | 09:01:16 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-dft) 09:01:16 INFO - PROCESS | 3933 | RSS: Main: 166617088 09:01:16 INFO - PROCESS | 3933 | 09:01:19 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-fft) 09:01:20 INFO - PROCESS | 3933 | RSS: Main: 163147776 09:01:20 INFO - PROCESS | 3933 | 09:01:22 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58335/tests/kraken/driver.html?testName=audio-oscillator) 09:01:23 INFO - PROCESS | 3933 | RSS: Main: 163557376 09:01:23 INFO - PROCESS | 3933 | 09:01:25 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58335/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:01:25 INFO - PROCESS | 3933 | RSS: Main: 164212736 09:01:25 INFO - PROCESS | 3933 | 09:01:29 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58335/tests/kraken/driver.html?testName=imaging-darkroom) 09:01:29 INFO - PROCESS | 3933 | RSS: Main: 168558592 09:01:29 INFO - PROCESS | 3933 | 09:01:33 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58335/tests/kraken/driver.html?testName=imaging-desaturate) 09:01:33 INFO - PROCESS | 3933 | RSS: Main: 165163008 09:01:33 INFO - PROCESS | 3933 | 09:01:36 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58335/tests/kraken/driver.html?testName=json-parse-financial) 09:01:37 INFO - PROCESS | 3933 | RSS: Main: 165625856 09:01:37 INFO - PROCESS | 3933 | 09:01:39 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58335/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:01:39 INFO - PROCESS | 3933 | RSS: Main: 166100992 09:01:39 INFO - PROCESS | 3933 | 09:01:41 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:01:42 INFO - PROCESS | 3933 | RSS: Main: 166334464 09:01:42 INFO - PROCESS | 3933 | 09:01:44 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:01:45 INFO - PROCESS | 3933 | RSS: Main: 167133184 09:01:45 INFO - PROCESS | 3933 | 09:01:47 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:01:48 INFO - PROCESS | 3933 | RSS: Main: 167149568 09:01:48 INFO - PROCESS | 3933 | 09:01:51 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:01:52 INFO - PROCESS | 3933 | RSS: Main: 166539264 09:01:52 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | Cycle 1(1): loaded http://localhost:58335/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58335/tests/kraken/driver.html?testName=ai-astar) 09:01:54 INFO - PROCESS | 3933 | RSS: Main: 168652800 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | __start_tp_report 09:01:54 INFO - PROCESS | 3933 | _x_x_mozilla_page_load 09:01:54 INFO - PROCESS | 3933 | _x_x_mozilla_page_load_details 09:01:54 INFO - PROCESS | 3933 | |i|pagename|runs| 09:01:54 INFO - PROCESS | 3933 | |0;ai-astar;110;116;114;116;114;116;116;113;113;117 09:01:54 INFO - PROCESS | 3933 | |1;audio-beat-detection;154;127;130;173;130;126;129;128;148;133 09:01:54 INFO - PROCESS | 3933 | |2;audio-dft;164;164;168;170;173;164;163;164;162;163 09:01:54 INFO - PROCESS | 3933 | |3;audio-fft;75;78;77;116;86;79;80;79;94;78 09:01:54 INFO - PROCESS | 3933 | |4;audio-oscillator;86;84;103;84;85;86;97;84;84;100 09:01:54 INFO - PROCESS | 3933 | |5;imaging-gaussian-blur;114;114;114;114;115;115;114;115;114;113 09:01:54 INFO - PROCESS | 3933 | |6;imaging-darkroom;152;151;150;150;150;150;150;152;149;150 09:01:54 INFO - PROCESS | 3933 | |7;imaging-desaturate;96;96;97;95;96;95;95;96;96;96 09:01:54 INFO - PROCESS | 3933 | |8;json-parse-financial;66;67;66;66;72;69;66;67;67;67 09:01:54 INFO - PROCESS | 3933 | |9;json-stringify-tinderbox;65;65;65;66;66;66;66;65;67;65 09:01:54 INFO - PROCESS | 3933 | |10;stanford-crypto-aes;89;87;91;85;89;88;91;88;91;89 09:01:54 INFO - PROCESS | 3933 | |11;stanford-crypto-ccm;138;140;149;135;134;136;137;141;196;138 09:01:54 INFO - PROCESS | 3933 | |12;stanford-crypto-pbkdf2;188;184;185;194;182;184;183;183;183;187 09:01:54 INFO - PROCESS | 3933 | |13;stanford-crypto-sha256-iterative;74;72;74;72;76;72;70;70;69;72 09:01:54 INFO - PROCESS | 3933 | __end_tp_report 09:01:54 INFO - PROCESS | 3933 | __start_cc_report 09:01:54 INFO - PROCESS | 3933 | _x_x_mozilla_cycle_collect,883 09:01:54 INFO - PROCESS | 3933 | __end_cc_report 09:01:54 INFO - PROCESS | 3933 | __startTimestamp1487264514543__endTimestamp 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | ------- Summary: start ------- 09:01:54 INFO - PROCESS | 3933 | Number of tests: 14 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#0] ai-astar Cycles:10 Average:114.50 Median:116.00 stddev:2.12 (1.8%) stddev-sans-first:1.50 09:01:54 INFO - PROCESS | 3933 | Values: 110.0 116.0 114.0 116.0 114.0 116.0 116.0 113.0 113.0 117.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:131.50 stddev:15.56 (11.8%) stddev-sans-first:15.36 09:01:54 INFO - PROCESS | 3933 | Values: 154.0 127.0 130.0 173.0 130.0 126.0 129.0 128.0 148.0 133.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#2] audio-dft Cycles:10 Average:165.50 Median:164.00 stddev:3.60 (2.2%) stddev-sans-first:3.77 09:01:54 INFO - PROCESS | 3933 | Values: 164.0 164.0 168.0 170.0 173.0 164.0 163.0 164.0 162.0 163.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#3] audio-fft Cycles:10 Average:84.20 Median:79.00 stddev:12.45 (15.8%) stddev-sans-first:12.76 09:01:54 INFO - PROCESS | 3933 | Values: 75.0 78.0 77.0 116.0 86.0 79.0 80.0 79.0 94.0 78.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:84.50 stddev:7.56 (8.9%) stddev-sans-first:7.92 09:01:54 INFO - PROCESS | 3933 | Values: 86.0 84.0 103.0 84.0 85.0 86.0 97.0 84.0 84.0 100.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 09:01:54 INFO - PROCESS | 3933 | Values: 114.0 114.0 114.0 114.0 115.0 115.0 114.0 115.0 114.0 113.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.00 stddev:0.97 (0.6%) stddev-sans-first:0.83 09:01:54 INFO - PROCESS | 3933 | Values: 152.0 151.0 150.0 150.0 150.0 150.0 150.0 152.0 149.0 150.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:01:54 INFO - PROCESS | 3933 | Values: 96.0 96.0 97.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#8] json-parse-financial Cycles:10 Average:67.30 Median:67.00 stddev:1.89 (2.8%) stddev-sans-first:1.94 09:01:54 INFO - PROCESS | 3933 | Values: 66.0 67.0 66.0 66.0 72.0 69.0 66.0 67.0 67.0 67.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 09:01:54 INFO - PROCESS | 3933 | Values: 65.0 65.0 65.0 66.0 66.0 66.0 66.0 65.0 67.0 65.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:89.00 stddev:1.93 (2.2%) stddev-sans-first:2.05 09:01:54 INFO - PROCESS | 3933 | Values: 89.0 87.0 91.0 85.0 89.0 88.0 91.0 88.0 91.0 89.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:139.00 stddev:18.61 (13.4%) stddev-sans-first:19.60 09:01:54 INFO - PROCESS | 3933 | Values: 138.0 140.0 149.0 135.0 134.0 136.0 137.0 141.0 196.0 138.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:184.50 stddev:3.59 (1.9%) stddev-sans-first:3.67 09:01:54 INFO - PROCESS | 3933 | Values: 188.0 184.0 185.0 194.0 182.0 184.0 183.0 183.0 183.0 187.0 09:01:54 INFO - PROCESS | 3933 | 09:01:54 INFO - PROCESS | 3933 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.00 stddev:2.13 (3.0%) stddev-sans-first:2.15 09:01:54 INFO - PROCESS | 3933 | Values: 74.0 72.0 74.0 72.0 76.0 72.0 70.0 70.0 69.0 72.0 09:01:54 INFO - PROCESS | 3933 | -------- Summary: end -------- 09:01:54 INFO - PROCESS | 3933 | 09:01:55 INFO - TEST-INFO | 3933: exit 0 09:01:55 INFO - TEST-OK | kraken | took 64887ms 09:01:55 INFO - SUITE-END | took 1187s 09:01:56 INFO - Completed test suite (00:19:47) 09:01:56 INFO - javascript benchmark 09:01:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6442.7078134137355, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2610.3865591585977, 2614.398688301689, 2632.7498887605684, 2639.274199595111, 2639.7624213820754, 4815.542523460274, 4828.4205895292525, 4851.2965619985125, 4869.026194761049, 4874.0, 4768.999999999991, 4783.186858234099, 4829.541193586612, 4892.266160075981, 4938.678985865916, 4173.908115460174, 4419.447569053868, 4516.796744146522, 4519.638428925695, 4540.636749060067, 4492.371068050459, 4492.730436173823, 4501.189785838547, 4507.594316511513, 4527.071950250191, 4447.955520444819, 4471.731696098244, 4485.977570112129, 4494.752788596626, 4528.9547104528865, 4479.081788233404, 4483.125790470859, 4483.30508771141, 4520.68355215135, 4531.637469002472, 4422.226110430694, 4440.33394990754, 4452.0205554777895, 4468.374427580141, 4505.121501307246, 4752.904941901177, 4755.0489902019735, 4782.689125206885, 4824.348712923746, 4904.926426103611, 5184.8963020739375, 5190.584753219733, 5228.215767634847, 5245.082110630625, 5279.44565820591, 5287.629865909388, 5291.047611429941, 5294.126469132589, 5313.123334649778, 5333.919991200135, 4764.6426518011285, 4767.594754445911, 4831.637627177975, 4937.728424936663, 4948.727819969864, 5179.922301165486, 5311.415744268127, 5378.623496355296, 5418.945810541923, 5428.185772134211, 4991.825286114969, 5080.669756465818, 5090.821821236239, 5114.181730923035, 5205.323307970016, 5205.24523944026, 5219.347581552306, 5235.1623740201385, 5237.842864713988, 5240.371155461293, 5090.0, 5126.794928202905, 5126.948730512647, 5165.690058596496, 5242.842714718565, 5070.264811602297, 5139.306193663882, 5145.511176438307, 5174.43081261061, 5202.2456743771945, 4363.454568178978, 4377.0808130293, 4397.692161548661, 4412.69111162222, 4423.292273236331, 3783.281176576442, 3807.9048023799073, 3817.923641527154, 3834.1373191031794, 3851.5763266040713, 4474.731516108979, 4513.435820522434, 4513.50351461339, 4517.751523666164, 4558.521355257716, 5211.0359583477175, 5212.478752124833, 5214.2960700304975, 5219.817306394258, 5256.474352564713, 4467.8212871484775, 4515.932261016087, 4522.954770452254, 4523.434570678665, 4565.246734288805, 4598.8620341389815, 4647.86056418308, 4654.953450465519, 4664.510226426244, 4686.461056978423, 4874.463808981081, 4917.483664215206, 4920.630952678492, 4921.3356196912955, 4931.7040977541465, 5206.0, 5213.0, 5216.530512253916, 5239.633225674166, 5250.737463126905, 4893.290472881484, 4909.4354149273295, 4920.827771027997, 4926.064047830901, 4926.137925862888, 5092.0579692756955, 5111.565516931102, 5123.820666276662, 5136.203888397305, 5141.357330333708, 5193.42872284056, 5203.271541984201, 5208.817691380784, 5213.947860521346, 5226.242194881797, 5073.289739436408, 5078.492150784892, 5097.592192624507, 5131.230315452712, 5137.229415587692, 5086.21163719624, 5092.567131793691, 5098.490150984871, 5100.081985242692, 5126.051680438982, 4869.342638743866, 4928.088303663833, 4948.861761794736, 4953.529414705597, 4976.10430122567, 5053.898922021613, 5059.848204553869, 5068.974655126701, 5097.133487307093, 5102.183650615883, 4931.3589233399425, 4937.950620493749, 4938.308636790924, 4945.307656927961, 4974.602031837517], "value": 4768.723395235326, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.3788729299426, 542.823582335741, 547.4142667345939, 549.8845242499075, 569.4220366328175, 36231.0, 38532.61467385329, 38601.806990965044, 38657.613423865754, 38703.61296387035, 39740.60259397405, 40088.59911400885, 40116.39825402614, 40210.19579608406, 40254.0, 7625.542467451943, 7785.182555831641, 7805.0, 7816.726414575491, 9354.906450935472, 9204.493752843624, 9361.625534978626, 9478.43129412234, 9522.143007129356, 9546.665866694668, 9475.289353298496, 9527.285453590974, 9539.332246742732, 9543.236541076732, 9559.330846840723, 9496.477693726842, 9510.191633711109, 9515.619375224982, 9573.329866909318, 9574.23406127515, 9410.247180225633, 9452.95273523628, 9460.148586627167, 9460.24318054554, 9466.857997130048, 33658.1585460363, 33963.490547641806, 33964.0, 33969.0, 34041.319173616386, 3306.6528014558367, 3448.8448019839166, 3466.722662187044, 3468.9132771680656, 3488.2151515908718, 25712.742872571223, 25828.483430331475, 25860.0, 26189.73810261892, 26210.606840897402, 3300.488424294238, 3321.983390083058, 3367.3097015111844, 3410.2838403934957, 3489.3370259650587, 228987.0, 229930.0, 231010.0, 231388.0, 231583.0, 39866.0, 41392.58607413948, 47024.0, 47141.764291178326, 47449.76275118603, 61028.0, 61256.69371653113, 66120.66939665271, 66632.6668366655, 67183.66408167928, 13038.608841734764, 13343.66640833987, 13742.725145497034, 13746.381412836447, 13751.243681597609, 9724.61101555944, 9741.853872191923, 9794.706158815246, 9817.55820988043, 9857.55640996157, 18957.957312347953, 19014.524636884187, 19138.330158444387, 19206.61586768257, 19412.902935485232, 6943.583384996917, 7493.550386976799, 7530.653236202248, 7579.1284002339335, 7659.540427574363, 768.8927943760966, 770.4722265248323, 797.0826774007106, 815.8205194857122, 817.627979269431, 27468.0, 28361.716382835908, 28479.430411391655, 28646.0, 28799.712002879704, 892.3084609427616, 905.7871400220943, 924.6393906376519, 941.3692825806589, 946.2666433514082, 14169.574912752634, 14641.194734289717, 14730.189839558712, 14877.33052012662, 14884.62788430298, 331981.0, 332036.0, 333987.0, 334737.0, 337280.0, 49531.0, 50040.749796250784, 50152.498475014785, 50156.0, 50221.49778502241, 1128.8137457319451, 1174.8942595166477, 1176.817593273044, 1184.8992835608897, 1191.9880801191878, 25335.36661583475, 26402.471950560495, 26630.20109396721, 27197.320066998484, 27305.180844574694, 41420.75737727873, 41502.792486037375, 41780.791096044326, 41792.16415671731, 41848.37227441652, 273836.0, 274617.0, 274690.0, 275234.0, 275712.0, 29792.553111703775, 30499.695003049685, 35009.47485787766, 36578.8171059143, 37304.813475932446, 36923.630763693094, 37458.2508349826, 38052.42921356237, 38224.23551529009, 38578.228435431694, 32389.838050809594, 32976.505352420216, 33325.33349333048, 33611.83194084014, 33629.83185084059, 32301.0, 32732.509012364357, 33910.0, 33943.83028084844, 34779.65220347764, 32252.67747322497, 32543.674563254066, 32593.0, 33093.66906330906, 33483.330333393686, 28094.066830643318, 28434.0, 29247.7075229245, 29341.706582933897, 30628.387432251675, 23147.305580832603, 23452.64821027652, 23458.413539661644, 23459.296221113393, 23682.763172368057], "value": 17342.956209155294, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.8634271176445, 404.9412835138903, 408.3629537920843, 416.5834165834166, 428.58213242088965, 17167.828321716777, 17644.206010729515, 17928.910355448206, 17973.730394044083, 18925.242990280407, 10784.892151078488, 10991.835122473149, 11033.448327583608, 16151.43469978551, 17630.11849407531, 4611.100835337103, 4773.451053128882, 4780.235162374021, 4786.521347865203, 4794.089123066624, 4550.453945526541, 4584.083183363324, 4635.745034023127, 4642.117997580466, 4645.163870503308, 4651.767411629422, 4657.464391594959, 4666.183417901871, 4680.625549955996, 4700.412448443944, 4492.97753511232, 4642.535746425364, 4653.88365290867, 4657.161710892038, 4688.7186768793645, 4586.449626044896, 4589.770511474447, 4589.908201835945, 4592.724436533786, 4596.655250856165, 11981.700457488545, 13128.934355328258, 21765.45586360338, 22166.889165554232, 22195.556088878293, 2413.9861258271394, 2424.248482970285, 2424.8181386395913, 2462.9014839406404, 2530.5571524983056, 8983.146601072887, 9074.63701451941, 14890.404383824634, 14984.325705343177, 15454.459093931771, 1839.9448016559525, 1856.3097158456385, 1863.431653345729, 1878.624275144963, 1882.105999650169, 87689.56155219184, 91984.08015919889, 93682.53158734163, 96690.9999999993, 97537.0, 17938.820611793977, 18283.72574411385, 18292.17685204169, 18389.81610183881, 18406.539836504195, 33199.83400082984, 33462.16344591405, 33490.83254583712, 33524.1618959528, 34026.829865850515, 8763.518006509703, 8792.648294068165, 8856.424332418372, 8931.910680893108, 9130.08699130095, 7060.682269297894, 7101.325374089453, 7266.164391094986, 7331.963340183265, 7344.742933997284, 9285.928519099358, 9320.30097742672, 9456.33805633599, 9463.59942626682, 9530.809383812424, 3471.1810554535273, 3634.67287944081, 3922.7254092213843, 3950.7036972227197, 4044.7168698190844, 801.2028032107974, 810.7973006748313, 817.8159914019417, 828.3704384667695, 850.9787255318543, 16836.24236909342, 16968.40610578624, 16974.0, 17108.315667373416, 17889.0, 802.8594995875699, 820.6799348254185, 825.3603457320625, 834.3408707121306, 836.4145098431122, 9249.0, 9498.0, 9855.80288394228, 9888.950555247178, 10039.648612298535, 468082.0, 477318.0, 479032.0, 481192.0, 493176.0, 65354.346456534826, 79691.0, 89341.0, 89543.55228223947, 90058.0, 963.2342287881152, 975.2539307429822, 998.2313618513704, 998.4558415663481, 1003.94478303694, 14884.702305954037, 15027.398904043715, 16187.595310117342, 17412.303507859542, 17868.642627147645, 40104.598954010085, 40985.590144098176, 41042.79478602588, 41830.79084604557, 41861.0, 49976.500234997184, 51358.743206285224, 53161.0, 53915.730421347645, 54407.18389224244, 7380.815479613053, 7525.661345239587, 7747.457677962489, 7802.960985195038, 7987.201279871966, 9044.909550904407, 11707.2975621463, 11870.82193767077, 12191.634250972485, 12291.078169137529, 9148.954255228948, 9214.40106393109, 9295.86056209171, 9404.529773511435, 9678.370905891376, 9937.0, 10346.37921724699, 10532.947335263274, 10629.681109566725, 11045.779084418427, 9209.309301802257, 9220.631174752934, 9223.216026637543, 9754.9024509754, 9905.306628536222, 7400.148982866824, 7495.775126746206, 7780.805479863048, 7795.610219489116, 7830.80422989407, 9359.906400936175, 9691.612335506783, 9999.55002024896, 9999.800004000024, 10430.25031208191], "value": 9061.38151804002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.6011196641005, 1335.5459143891069, 1336.024701967563, 1337.6187786480853, 1341.0344551922615, 7200.991861139438, 7357.0803649543805, 7511.286427789351, 7628.580428076453, 7650.234976502347, 7396.186419493852, 7497.550146991171, 7503.898908305683, 7509.7747067587925, 7596.810079748009, 2114.0, 2141.946451338721, 2152.1283880028614, 2156.0836644426136, 2156.590247852903, 2102.9579408411823, 2146.3453646637768, 2161.384005558416, 2161.7730138335482, 2169.4467910682833, 2089.1434511850143, 2107.388857231401, 2118.724565806443, 2132.6694362373855, 2135.135270215565, 2089.550746589483, 2105.6420408530585, 2107.8524503284775, 2108.673155660875, 2114.8202402795705, 1789.2216885654782, 1840.429466865276, 2035.735354403933, 2037.9388618341472, 2123.0870725587993, 7410.777676669709, 7503.849923001564, 7684.077910650702, 7684.308412242869, 7703.805910083946, 4660.277656963176, 4814.879628009293, 4820.6143508519235, 4823.252395878644, 4825.806967721322, 2840.573913912909, 2913.0678182981455, 2943.45546073977, 2953.675095739467, 2966.6143401357685, 1171.3733152763316, 1199.1905463811893, 1199.2624535910352, 1204.4098391788048, 1206.0652993929687, 10259.79480410395, 10394.376337419704, 10447.634332798392, 10477.371357718484, 10478.10936070435, 4027.194561087794, 4253.659707223414, 4324.870253892388, 4328.675349348811, 4332.263515202423, 8900.198982091642, 8949.418287811402, 9006.054364291786, 9038.909610903938, 9041.095890411038, 4793.712377257306, 4823.397075365579, 4828.686135401188, 4836.177849765549, 4854.68444551103, 4236.491621005499, 4292.463442069741, 4300.440942677495, 4389.407429996973, 4392.450943632046, 3625.4743062255825, 3680.742348035612, 3744.737868349244, 3751.456038874348, 3769.5288088988877, 1239.5971309324505, 1245.2777389114292, 1249.0694432647736, 1257.9433925473375, 1272.5609664665676, 691.325957191734, 707.6320313436984, 719.9424046076301, 730.8794048981858, 747.0698979770112, 4433.202023635712, 4445.866624001285, 4489.416375871181, 4496.258117410656, 4599.1721490132095, 476.31172955079575, 480.075853981085, 490.2156549520738, 492.4140273075029, 498.61606562946326, 2855.500287449688, 2872.6409198850142, 2944.2344990302677, 2948.5871977923107, 2960.985195074054, 8127.512349259064, 8518.914810851813, 8541.0, 8730.607122679494, 8968.417052891542, 9071.81856362869, 9113.179813816789, 9137.451752894847, 9143.13140251675, 9176.632934682671, 6907.13660792401, 7161.104861892263, 7217.530860494051, 7244.818879527948, 7268.418526517866, 4360.324149756792, 4401.537838526909, 4404.757738324423, 4430.512643609265, 4498.460184777848, 8189.262966332821, 8345.165483451607, 8367.790805229917, 8377.204165604258, 8441.480533504027, 8381.203785640355, 8400.579971001303, 8470.068292487944, 8476.449030812977, 8672.913270867211, 6116.357782432959, 6163.876722465615, 6187.721552530237, 6194.876102478015, 6222.595531290452, 6868.210155831944, 6903.413209877217, 6906.585604863724, 6955.339242771928, 6966.407855332353, 6477.4170324671, 6485.481161506974, 6531.706073226811, 6554.049662798772, 6643.335666433318, 6306.495480361468, 6310.368963103653, 6327.303996560283, 6357.841053973688, 6358.93641063602, 5344.305240318734, 5359.785608575769, 5361.812336568201, 5393.541548968382, 5397.190421436659, 3887.300285948443, 3935.0162459385156, 4005.4592629995745, 4044.3326851069246, 4111.958880411157, 4130.648894843972, 4136.8003279048735, 4239.576042395735, 4278.165757677223, 4332.068605249887], "value": 3901.587912651242, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.83900325351965, 356.5489655585684, 360.79795314623794, 367.24714335612003, 370.0305200375018, 5101.10730622141, 5114.488551144884, 5115.488451154883, 5122.180451127821, 5130.358705161855, 5029.899402011957, 5043.218301163316, 5051.040302342552, 5063.0, 5066.74666266687, 3744.3073031489193, 3872.224581033484, 3886.533615966088, 3890.513685789262, 3927.5286965564173, 3872.9419058714143, 3886.650201481866, 3899.181171953893, 3915.7846318452475, 3922.7057970652313, 3840.788756618385, 3858.0, 3867.9226415471676, 3873.573906870242, 3875.934118117512, 3630.2920930418577, 3834.616538346171, 3856.2094770572107, 3878.26313000529, 3882.0683036071423, 3371.0561042908025, 3388.220709236865, 3403.9829800851085, 3408.568401271472, 3427.845746941394, 4938.9012219755405, 4947.158982972904, 4956.975215123901, 4957.107720610289, 4961.131801934647, 4474.2393793054935, 4495.57292057254, 4495.887602809923, 4522.163399771053, 4535.659825513099, 4969.478204788517, 5149.407818100892, 5227.764750586232, 5232.2674825524455, 5232.424433312332, 3692.316921369555, 3701.7223708221723, 3715.851365945359, 3722.9813850930573, 3736.7384283100196, 5307.815310813519, 5489.560835133179, 5517.337919449652, 5551.472610102034, 5568.109102543614, 3717.1450566369886, 3748.7188460865545, 3754.1553150541454, 3772.396416573335, 3777.58446570877, 3786.9431958520645, 3822.025383527183, 3825.2923209206388, 3838.098046958962, 3848.5381754189675, 4384.824607015684, 4405.647548196201, 4412.61002784122, 4425.889352766207, 4436.183023779923, 5061.747522352518, 5268.789248430096, 5277.050130976461, 5286.920696189561, 5300.390455097636, 1300.6878349196124, 1311.0363882546321, 1317.5638553976212, 1317.9802302965463, 1324.5231716582023, 841.7769291137731, 889.6886089868494, 891.4562117108559, 899.5771987166153, 907.9092090790868, 4475.328700694857, 4484.215262329079, 4490.418009644468, 4491.955080449219, 4493.213687604656, 5253.212018197224, 5278.683279003272, 5291.391489978701, 5317.8404647860625, 5326.36083669962, 4306.89232769177, 4505.391772110723, 4552.565941728345, 4556.476005259371, 4559.566841150086, 2799.5380762174013, 2800.28592708862, 2805.298675331167, 2810.7048759880327, 2836.886524538995, 4956.430010548761, 4963.60291176705, 4964.726940018261, 4990.226514890198, 4993.177490216118, 5113.826623684993, 5381.973090134603, 5428.538574221236, 5436.238926550286, 5440.727963601804, 4981.726005069756, 4986.1551226046595, 4988.127077761377, 5004.974975125101, 5041.823536176216, 5184.559312458483, 5263.710495922761, 5274.815381461631, 5285.339332583427, 5340.893182136258, 4896.947156361257, 5122.974385128051, 5144.6398752087, 5153.948460515347, 5230.790768369375, 4906.803727850846, 4929.672471082077, 4933.284673722362, 4980.651354405157, 5072.85483000542, 4070.3080476318505, 4104.158647477319, 4140.296149654506, 4143.274926887722, 4163.8334466621, 5486.506214440719, 5513.531349835309, 5518.972405137949, 5520.254765606753, 5526.696031718133, 5167.328247327973, 5196.948030519798, 5222.190560463135, 5298.873664537941, 5353.384360798403, 5225.503577160163, 5266.183741520071, 5304.7347632618985, 5331.466853314638, 5342.706151161723, 5231.476852314738, 5247.711375874211, 5270.578353731616, 5273.31446911899, 5304.9469505306, 5103.617228707788, 5288.312519372457, 5303.549198318032, 5354.732263386893, 5355.544778693855, 5182.352205974254, 5199.7140157291715, 5204.646791834075, 5217.869553261047, 5229.111051121243], "value": 3955.0062277678426, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.9085137229413, 645.6932956845496, 647.1845474701875, 659.9604023758575, 666.4368608525795, 12503.749925001506, 13248.47006119754, 13428.798568021475, 13463.0, 14248.28758562073, 12616.243025418458, 12791.50886984254, 12908.741825163492, 12933.288669123194, 12964.092513524058, 788.2117882117868, 800.9199080092003, 810.0481933727875, 810.1574362662824, 814.1085511365076, 796.3231253434564, 811.1726039439768, 820.9425340226187, 827.9668813247463, 831.3141658132035, 843.7510934274404, 848.7156802471179, 849.5539841583187, 851.2849206666399, 852.7484392104313, 841.0202114536557, 842.2924743215729, 843.2747836860295, 843.4011851585382, 847.8982522097357, 622.9968850155766, 623.3081279779441, 624.1792043462832, 625.8560531077832, 626.2203556572051, 13251.403686834115, 13477.191368517822, 13503.797443038362, 13508.324583770773, 14053.0, 11948.0, 12124.393780311037, 12135.271883686919, 12282.078844086729, 12361.814572781415, 9946.162788957123, 9991.800163996752, 10073.496325183785, 10107.292489525737, 10139.290249682525, 3016.095171448557, 3092.118746157357, 3103.239706271978, 3184.4268031754277, 3185.697358750915, 61267.693661531855, 61282.0, 61786.69106654483, 62318.376816231714, 62372.68813655948, 10003.499825008721, 10072.496375181358, 10172.491375431198, 10285.640002599874, 10314.123299519624, 19181.80818191828, 19239.422817315502, 19269.325573605136, 19375.996720508345, 19381.321653742052, 5589.832305030855, 5604.551635869121, 5674.319081710221, 5823.737931793079, 5853.502452291517, 3168.413843438925, 3247.691469310412, 3280.4423248047888, 3296.225387034045, 3303.1969800481447, 3105.7981231219896, 3232.563603913489, 3238.5789847319697, 3239.5788547489146, 3241.589908389858, 512.7564406906689, 513.4147072337538, 523.4268476018754, 523.9030779305841, 582.128843192763, 560.1485741672635, 564.9378568357478, 584.1354794903566, 601.1913975702673, 617.783775678509, 8950.0, 8960.238379737664, 9006.954965225132, 9406.623735050522, 9424.811503769886, 471.4920987850382, 474.08738179005275, 481.6147082334119, 482.10088185533726, 487.8902246994469, 4114.053767633402, 4220.873373798791, 4287.485501739811, 4326.675499337499, 4392.802323895432, 62197.689011554656, 63057.68471157615, 64162.0, 65624.0, 66356.0, 58820.0, 59407.10889336663, 59477.70261148753, 59490.70254648699, 59740.0, 49977.0, 50095.0, 51726.74136629368, 51882.22176667353, 52000.0, 8042.396820238622, 8291.0, 8410.6215220314, 8487.36344774132, 8576.142385761374, 29574.556381653863, 30199.849000754857, 30273.848630756704, 33167.502487462225, 36600.0, 40339.596604033584, 42114.0, 43116.35325470183, 43511.0, 44300.55699443093, 10079.143272821892, 10124.848127278245, 10260.486975651336, 10423.791524169323, 10779.461026948778, 12408.0, 12682.0, 12792.232466052066, 12987.610371688865, 13434.529791457253, 11688.0, 11912.344821034927, 12096.758064838477, 12269.87730122723, 12697.682557936125, 11539.249948753304, 11812.586559470377, 11845.703857403634, 11957.641270761891, 12131.029517638392, 11219.270747401393, 11820.82268765952, 11873.821892671443, 11879.346635935106, 12218.327991960528, 9409.623615055594, 9512.310253161468, 9641.855372169282, 9757.316987810786, 9986.0, 7793.532388056735, 8762.342824288331, 8839.823203536022, 9128.224100951493, 9185.127412895765], "value": 6183.743176017165, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 114.0, 116.0, 114.0, 116.0, 116.0, 113.0, 113.0, 117.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 127.0, 130.0, 173.0, 130.0, 126.0, 129.0, 128.0, 148.0, 133.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 168.0, 170.0, 173.0, 164.0, 163.0, 164.0, 162.0, 163.0], "value": 165.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 77.0, 116.0, 86.0, 79.0, 80.0, 79.0, 94.0, 78.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 103.0, 84.0, 85.0, 86.0, 97.0, 84.0, 84.0, 100.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 115.0, 114.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 150.0, 150.0, 150.0, 150.0, 150.0, 152.0, 149.0, 150.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 72.0, 69.0, 66.0, 67.0, 67.0, 67.0], "value": 67.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 85.0, 89.0, 88.0, 91.0, 88.0, 91.0, 89.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 149.0, 135.0, 134.0, 136.0, 137.0, 141.0, 196.0, 138.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 185.0, 194.0, 182.0, 184.0, 183.0, 183.0, 183.0, 187.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 74.0, 72.0, 76.0, 72.0, 70.0, 70.0, 69.0, 72.0], "value": 72.1, "unit": "score"}]}]} 09:01:56 INFO - Return code: 0 09:01:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:01:56 ERROR - # TBPL SUCCESS # 09:01:56 INFO - Running post-action listener: _resource_record_post_action 09:01:56 INFO - [mozharness: 2017-02-16 17:01:56.332057Z] Finished run-tests step (success) 09:01:56 INFO - Running post-run listener: _resource_record_post_run 09:01:56 INFO - Total resource usage - Wall time: 1204s; CPU: 14.0%; Read bytes: 7290880; Write bytes: 404111360; Read time: 1000; Write time: 305016 09:01:56 INFO - TinderboxPrint: CPU usage
13.5% 09:01:56 INFO - TinderboxPrint: I/O read bytes / time
7,290,880 / 1,000 09:01:56 INFO - TinderboxPrint: I/O write bytes / time
404,111,360 / 305,016 09:01:56 INFO - TinderboxPrint: CPU idle
8,310.8 (86.5%) 09:01:56 INFO - TinderboxPrint: CPU user
1,237.3 (12.9%) 09:01:56 INFO - TinderboxPrint: Swap in / out
0 / 0 09:01:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106483712; Read time: 0; Write time: 106464 09:01:56 INFO - run-tests - Wall time: 1188s; CPU: 14.0%; Read bytes: 5259264; Write bytes: 250613760; Read time: 756; Write time: 175976 09:01:56 INFO - Running post-run listener: _upload_blobber_files 09:01:56 INFO - Blob upload gear active. 09:01:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:01:56 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 09:01:56 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'] 09:01:56 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 09:01:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:01:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:01:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:01:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:58 INFO - (blobuploader) - INFO - Done attempting. 09:01:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:01:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:01:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:59 INFO - (blobuploader) - INFO - Done attempting. 09:01:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:01:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:00 INFO - (blobuploader) - INFO - Done attempting. 09:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:02:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:03 INFO - (blobuploader) - INFO - Done attempting. 09:02:03 INFO - (blobuploader) - INFO - Iteration through files over. 09:02:03 INFO - Return code: 0 09:02:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:02:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:02:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17150d2d4c2eb23165d148a13c365dead8573e12ed8f2d41ea612def9dfb136742a30b34c96d12b1126857887e4d36da7ef568db7c60126dea702c78cba9c2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a42c17e60117990c4e6418b9986212cd59ad5e5206263df73f83981efb79f1304c3a6727cc0fa77d2c33977a328928e40abb7f9b056efe456e4a2d0fbc65063e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b145b04e16ecdad6233ea00a20525fb3cf2d5f182519bc3d2041d4884e86ba1a5962abe321927da32bfff4762ee44b361b51644c23ab825d342915d56b29ecd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d17750e8ad923354f0325723a3a950826f9ef1995e868a1792f4b3d929fec4b671ce328e1b7eb8e9227dc2c8797a43ca571123742b41e7e9cb5901637f0d7f3b"} 09:02:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:02:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:02:03 INFO - Contents: 09:02:03 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17150d2d4c2eb23165d148a13c365dead8573e12ed8f2d41ea612def9dfb136742a30b34c96d12b1126857887e4d36da7ef568db7c60126dea702c78cba9c2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a42c17e60117990c4e6418b9986212cd59ad5e5206263df73f83981efb79f1304c3a6727cc0fa77d2c33977a328928e40abb7f9b056efe456e4a2d0fbc65063e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b145b04e16ecdad6233ea00a20525fb3cf2d5f182519bc3d2041d4884e86ba1a5962abe321927da32bfff4762ee44b361b51644c23ab825d342915d56b29ecd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d17750e8ad923354f0325723a3a950826f9ef1995e868a1792f4b3d929fec4b671ce328e1b7eb8e9227dc2c8797a43ca571123742b41e7e9cb5901637f0d7f3b"} 09:02:03 INFO - Running post-run listener: copy_logs_to_upload_dir 09:02:03 INFO - Copying logs to upload dir... 09:02:03 INFO - mkdir: /builds/slave/test/build/upload/logs 09:02:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.698978 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-16 09:02:03.880296) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 09:02:03.883483) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17150d2d4c2eb23165d148a13c365dead8573e12ed8f2d41ea612def9dfb136742a30b34c96d12b1126857887e4d36da7ef568db7c60126dea702c78cba9c2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a42c17e60117990c4e6418b9986212cd59ad5e5206263df73f83981efb79f1304c3a6727cc0fa77d2c33977a328928e40abb7f9b056efe456e4a2d0fbc65063e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b145b04e16ecdad6233ea00a20525fb3cf2d5f182519bc3d2041d4884e86ba1a5962abe321927da32bfff4762ee44b361b51644c23ab825d342915d56b29ecd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d17750e8ad923354f0325723a3a950826f9ef1995e868a1792f4b3d929fec4b671ce328e1b7eb8e9227dc2c8797a43ca571123742b41e7e9cb5901637f0d7f3b"} build_url:https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011061 build_url: 'https://queue.taskcluster.net/v1/task/GF__qv5TRRS2uPzZ7Tm1eg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17150d2d4c2eb23165d148a13c365dead8573e12ed8f2d41ea612def9dfb136742a30b34c96d12b1126857887e4d36da7ef568db7c60126dea702c78cba9c2c5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a42c17e60117990c4e6418b9986212cd59ad5e5206263df73f83981efb79f1304c3a6727cc0fa77d2c33977a328928e40abb7f9b056efe456e4a2d0fbc65063e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b145b04e16ecdad6233ea00a20525fb3cf2d5f182519bc3d2041d4884e86ba1a5962abe321927da32bfff4762ee44b361b51644c23ab825d342915d56b29ecd7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d17750e8ad923354f0325723a3a950826f9ef1995e868a1792f4b3d929fec4b671ce328e1b7eb8e9227dc2c8797a43ca571123742b41e7e9cb5901637f0d7f3b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 09:02:03.907916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:02:03.908270) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1487263253.801670-1708166168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:02:03.989915) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 09:02:03.990247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 09:02:03.990545) ========= ========= Total master_lag: 0.18 =========